builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0208 starttime: 1475361066.49 results: success (0) buildid: 20161001140155 builduid: f80e5b28e5fa4ab6b86efd293eb80da9 revision: 7c576fe3279d87543f0a03b844eba7bc215e17f1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:06.487023) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:06.487484) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:06.487766) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:06.517970) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:06.518382) ========= 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.By3fmIZHpj/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.DWVdRKrzpQ/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-01 15:31:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.1M=0.001s 2016-10-01 15:31:06 (19.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113007 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:06.653849) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:06.657294) ========= 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.By3fmIZHpj/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.DWVdRKrzpQ/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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.026798 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:06.705902) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:06.709287) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7c576fe3279d87543f0a03b844eba7bc215e17f1 --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 7c576fe3279d87543f0a03b844eba7bc215e17f1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.By3fmIZHpj/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.DWVdRKrzpQ/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-01 15:31:06,797 truncating revision to first 12 chars 2016-10-01 15:31:06,797 Setting DEBUG logging. 2016-10-01 15:31:06,797 attempt 1/10 2016-10-01 15:31:06,797 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7c576fe3279d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-01 15:31:07,098 unpacking tar archive at: mozilla-central-7c576fe3279d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.582687 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:07.313844) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:07.317263) ========= script_repo_revision: 7c576fe3279d87543f0a03b844eba7bc215e17f1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:07.317728) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:07.318012) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:07.336417) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-01 15:31:07.336806) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--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.By3fmIZHpj/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.DWVdRKrzpQ/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 15:31:07 INFO - MultiFileLogger online at 20161001 15:31:07 in /builds/slave/test 15:31:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:31:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:31:07 INFO - {'append_to_log': False, 15:31:07 INFO - 'base_work_dir': '/builds/slave/test', 15:31:07 INFO - 'blob_upload_branch': 'Firefox', 15:31:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:31:07 INFO - 'branch': 'Firefox', 15:31:07 INFO - 'buildbot_json_path': 'buildprops.json', 15:31:07 INFO - 'config_files': ('talos/mac_config.py',), 15:31:07 INFO - 'default_actions': ('clobber', 15:31:07 INFO - 'read-buildbot-config', 15:31:07 INFO - 'download-and-extract', 15:31:07 INFO - 'populate-webroot', 15:31:07 INFO - 'create-virtualenv', 15:31:07 INFO - 'install', 15:31:07 INFO - 'run-tests'), 15:31:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:31:07 INFO - 'download_minidump_stackwalk': True, 15:31:07 INFO - 'download_symbols': 'ondemand', 15:31:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:31:07 INFO - 'tooltool.py': '/tools/tooltool.py', 15:31:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:31:07 INFO - '/tools/misc-python/virtualenv.py')}, 15:31:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:31:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:31:07 INFO - 'installer_path': 'installer.exe', 15:31:07 INFO - 'log_level': 'info', 15:31:07 INFO - 'log_name': 'talos', 15:31:07 INFO - 'log_to_console': True, 15:31:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:31:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:31:07 INFO - 'opt_config_files': (), 15:31:07 INFO - 'pip_index': False, 15:31:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:31:07 INFO - 'cmd': ('bash', 15:31:07 INFO - '-c', 15:31:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:31:07 INFO - 'enabled': True, 15:31:07 INFO - 'halt_on_failure': False, 15:31:07 INFO - 'name': 'check_screen_resolution'},), 15:31:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:31:07 INFO - 'cmd': ('bash', 15:31:07 INFO - '-c', 15:31:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:31:07 INFO - 'enabled': True, 15:31:07 INFO - 'halt_on_failure': False, 15:31:07 INFO - 'name': 'check_screen_resolution'},), 15:31:07 INFO - 'run_cmd_checks_enabled': True, 15:31:07 INFO - 'sps_profile': False, 15:31:07 INFO - 'sps_profile_interval': 0, 15:31:07 INFO - 'suite': 'dromaeojs', 15:31:07 INFO - 'system_bits': '32', 15:31:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:31:07 INFO - 'title': 't-yosemite-r7-0208', 15:31:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:31:07 INFO - 'use_talos_json': True, 15:31:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:31:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:31:07 INFO - 'work_dir': 'build'} 15:31:07 INFO - [mozharness: 2016-10-01 22:31:07.543150Z] Running clobber step. 15:31:07 INFO - Running pre-action listener: _resource_record_pre_action 15:31:07 INFO - Running main action method: clobber 15:31:07 INFO - rmtree: /builds/slave/test/build 15:31:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:31:09 INFO - Running post-action listener: _resource_record_post_action 15:31:09 INFO - [mozharness: 2016-10-01 22:31:09.593819Z] Finished clobber step (success) 15:31:09 INFO - [mozharness: 2016-10-01 22:31:09.593962Z] Running read-buildbot-config step. 15:31:09 INFO - Running pre-action listener: _resource_record_pre_action 15:31:09 INFO - Running main action method: read_buildbot_config 15:31:09 INFO - Using buildbot properties: 15:31:09 INFO - { 15:31:09 INFO - "project": "", 15:31:09 INFO - "product": "firefox", 15:31:09 INFO - "script_repo_revision": "production", 15:31:09 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 15:31:09 INFO - "repository": "", 15:31:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 15:31:09 INFO - "buildid": "20161001140155", 15:31:09 INFO - "pgo_build": "False", 15:31:09 INFO - "basedir": "/builds/slave/test", 15:31:09 INFO - "buildnumber": 60, 15:31:09 INFO - "slavename": "t-yosemite-r7-0208", 15:31:09 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:31:09 INFO - "platform": "yosemite_r7", 15:31:09 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:31:09 INFO - "branch": "mozilla-central", 15:31:09 INFO - "repo_path": "mozilla-central", 15:31:09 INFO - "revision": "7c576fe3279d87543f0a03b844eba7bc215e17f1", 15:31:09 INFO - "stage_platform": "macosx64", 15:31:09 INFO - "builduid": "f80e5b28e5fa4ab6b86efd293eb80da9", 15:31:09 INFO - "slavebuilddir": "test" 15:31:09 INFO - } 15:31:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 15:31:09 INFO - Running post-action listener: _resource_record_post_action 15:31:09 INFO - [mozharness: 2016-10-01 22:31:09.597368Z] Finished read-buildbot-config step (success) 15:31:09 INFO - [mozharness: 2016-10-01 22:31:09.597515Z] Running download-and-extract step. 15:31:09 INFO - Running pre-action listener: _resource_record_pre_action 15:31:09 INFO - Running main action method: download_and_extract 15:31:09 INFO - mkdir: /builds/slave/test/build/tests 15:31:09 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')]} 15:31:09 INFO - trying https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 15:31:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:31:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 15:31:10 INFO - Downloaded 1199 bytes. 15:31:10 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:31:10 INFO - Using the following test package requirements: 15:31:10 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:31:10 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:10 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 15:31:10 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:10 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 15:31:10 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:10 INFO - u'jsshell-mac.zip'], 15:31:10 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:10 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 15:31:10 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:31:10 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:10 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 15:31:10 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:10 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 15:31:10 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:10 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 15:31:10 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:10 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 15:31:10 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:31:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:31:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:31:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:31:11 INFO - Http code: 200 15:31:11 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 15:31:11 INFO - x-amz-cf-id: OzHZ20NcNoeGpxUBfc9n4ZP3cI0PIqdAc5okBWs-8bkT1BgYuzddIA== 15:31:11 INFO - x-amz-version-id: jJMdINxSRczli9FwMrnCw2KZ4NYRLaYk 15:31:11 INFO - Expected file size: 16475685 15:31:11 INFO - Obtained file size: 16475685 15:31:18 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:31:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:31:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:31:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:31:19 INFO - Http code: 200 15:31:19 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 15:31:19 INFO - x-amz-cf-id: 2hy-o7_b4GCYSSkwmvWKlT_6oAcQ8iqmqQ6-OzUUC7iXPvKNYdA8Ow== 15:31:19 INFO - x-amz-version-id: jJMdINxSRczli9FwMrnCw2KZ4NYRLaYk 15:31:19 INFO - Expected file size: 16475685 15:31:19 INFO - Obtained file size: 16475685 15:31:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 15:31:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:31:26 INFO - Fetch https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 15:31:27 INFO - Http code: 200 15:31:27 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 15:31:27 INFO - x-amz-cf-id: bn535kIKyLyYvIKWdF-bj7fBxExkYqSl2qy2GOP5RU4RQgiXxCI8kg== 15:31:27 INFO - x-amz-version-id: sCW5HqaOW.c0I__eMDsUeaQ03u9DBWZS 15:31:27 INFO - Expected file size: 14408522 15:31:27 INFO - Obtained file size: 14408522 15:31:27 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')]} 15:31:27 INFO - trying https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:31:27 INFO - Downloading https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 15:31:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 15:31:30 INFO - Downloaded 111818313 bytes. 15:31:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:31:30 INFO - mkdir: /builds/slave/test/properties 15:31:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:31:30 INFO - Writing to file /builds/slave/test/properties/build_url 15:31:30 INFO - Contents: 15:31:30 INFO - build_url:https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:31:31 INFO - Running post-action listener: _resource_record_post_action 15:31:31 INFO - Running post-action listener: set_extra_try_arguments 15:31:31 INFO - [mozharness: 2016-10-01 22:31:31.161548Z] Finished download-and-extract step (success) 15:31:31 INFO - [mozharness: 2016-10-01 22:31:31.161665Z] Running populate-webroot step. 15:31:31 INFO - Running pre-action listener: _resource_record_pre_action 15:31:31 INFO - Running main action method: populate_webroot 15:31:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:31:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:31:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:31:31 INFO - '--tppagecycles', 15:31:31 INFO - '7'], 15:31:31 INFO - 'tests': ['tsvgm']}}, 15:31:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:31:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:31:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:31:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:31:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:31:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:31:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:31 INFO - 'talos_options': ['--disable-e10s'], 15:31:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:31:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:31:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:31 INFO - 'talos_options': ['--disable-e10s'], 15:31:31 INFO - 'tests': ['damp', 'tps']}, 15:31:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:31 INFO - 'tests': ['damp', 'tps']}, 15:31:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:31:31 INFO - 'tests': ['dromaeo_dom']}, 15:31:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:31:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:31:31 INFO - 'tests': ['basic_compositor_video']}, 15:31:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:31:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:31:31 INFO - 'tests': ['a11yr', 15:31:31 INFO - 'ts_paint', 15:31:31 INFO - 'tpaint', 15:31:31 INFO - 'sessionrestore', 15:31:31 INFO - 'sessionrestore_no_auto_restore', 15:31:31 INFO - 'tabpaint']}, 15:31:31 INFO - 'other-e10s': {'tests': ['a11yr', 15:31:31 INFO - 'ts_paint', 15:31:31 INFO - 'tpaint', 15:31:31 INFO - 'sessionrestore', 15:31:31 INFO - 'sessionrestore_no_auto_restore', 15:31:31 INFO - 'tabpaint']}, 15:31:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:31:31 INFO - 'ts_paint', 15:31:31 INFO - 'tpaint', 15:31:31 INFO - 'sessionrestore', 15:31:31 INFO - 'sessionrestore_no_auto_restore', 15:31:31 INFO - 'tabpaint']}, 15:31:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:31:31 INFO - 'ts_paint', 15:31:31 INFO - 'tpaint', 15:31:31 INFO - 'sessionrestore', 15:31:31 INFO - 'sessionrestore_no_auto_restore', 15:31:31 INFO - 'tabpaint']}, 15:31:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:31:31 INFO - 'tests': ['a11yr', 15:31:31 INFO - 'ts_paint', 15:31:31 INFO - 'tpaint', 15:31:31 INFO - 'sessionrestore', 15:31:31 INFO - 'sessionrestore_no_auto_restore', 15:31:31 INFO - 'tabpaint']}, 15:31:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:31:31 INFO - 'tests': ['a11yr', 15:31:31 INFO - 'ts_paint', 15:31:31 INFO - 'tpaint', 15:31:31 INFO - 'sessionrestore', 15:31:31 INFO - 'sessionrestore_no_auto_restore', 15:31:31 INFO - 'tabpaint']}, 15:31:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:31:31 INFO - 'tests': ['tsvgx', 15:31:31 INFO - 'tsvgr_opacity', 15:31:31 INFO - 'tart', 15:31:31 INFO - 'tscrollx', 15:31:31 INFO - 'cart']}, 15:31:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:31:31 INFO - 'tsvgr_opacity', 15:31:31 INFO - 'tart', 15:31:31 INFO - 'tscrollx', 15:31:31 INFO - 'cart']}, 15:31:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:31 INFO - 'talos_options': ['--disable-e10s'], 15:31:31 INFO - 'tests': ['tp5o']}, 15:31:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:31 INFO - 'tests': ['tp5o']}, 15:31:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:31 INFO - 'talos_options': ['--disable-e10s', 15:31:31 INFO - '--xperf_path', 15:31:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:31:31 INFO - 'tests': ['tp5n']}, 15:31:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:31 INFO - 'talos_options': ['--xperf_path', 15:31:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:31:31 INFO - 'tests': ['tp5n']}}, 15:31:31 INFO - 'talos.zip': {'path': '', 15:31:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:31:31 INFO - Running post-action listener: _resource_record_post_action 15:31:31 INFO - [mozharness: 2016-10-01 22:31:31.180559Z] Finished populate-webroot step (success) 15:31:31 INFO - [mozharness: 2016-10-01 22:31:31.180663Z] Running create-virtualenv step. 15:31:31 INFO - Running pre-action listener: _resource_record_pre_action 15:31:31 INFO - Running main action method: create_virtualenv 15:31:31 INFO - Creating virtualenv /builds/slave/test/build/venv 15:31:31 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 15:31:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:31:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:31:31 INFO - Using real prefix '/tools/python27' 15:31:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:31:32 INFO - Installing distribute.............................................................................................................................................................................................done. 15:31:34 INFO - Installing pip.................done. 15:31:34 INFO - Return code: 0 15:31:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:31:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:31:35 INFO - Reading from file tmpfile_stdout 15:31:35 INFO - Output received: 15:31:35 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) 15:31:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:31:35 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')]} 15:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:35 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')]} 15:31:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:35 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 0x10f65a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5d0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe722dbcd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46ed30>, '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 0x10f294db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f466b20>, '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.By3fmIZHpj/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.DWVdRKrzpQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 15:31:35 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 15:31:35 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 15:31:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By3fmIZHpj/Render', 15:31:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:35 INFO - 'HOME': '/Users/cltbld', 15:31:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:35 INFO - 'LOGNAME': 'cltbld', 15:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:35 INFO - 'MOZ_NO_REMOTE': '1', 15:31:35 INFO - 'NO_EM_RESTART': '1', 15:31:35 INFO - 'PAGER': '/bin/cat', 15:31:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:35 INFO - 'PWD': '/builds/slave/test', 15:31:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:35 INFO - 'SHELL': '/bin/bash', 15:31:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWVdRKrzpQ/Listeners', 15:31:35 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 15:31:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:35 INFO - 'USER': 'cltbld', 15:31:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:35 INFO - 'XPC_FLAGS': '0x0', 15:31:35 INFO - 'XPC_SERVICE_NAME': '0', 15:31:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:35 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 15:31:35 INFO - Cleaning up... 15:31:35 INFO - Return code: 0 15:31:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:31:35 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')]} 15:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:35 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')]} 15:31:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:35 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 0x10f65a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5d0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe722dbcd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46ed30>, '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 0x10f294db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f466b20>, '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.By3fmIZHpj/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.DWVdRKrzpQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 15:31:35 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 15:31:35 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 15:31:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By3fmIZHpj/Render', 15:31:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:35 INFO - 'HOME': '/Users/cltbld', 15:31:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:35 INFO - 'LOGNAME': 'cltbld', 15:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:35 INFO - 'MOZ_NO_REMOTE': '1', 15:31:35 INFO - 'NO_EM_RESTART': '1', 15:31:35 INFO - 'PAGER': '/bin/cat', 15:31:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:35 INFO - 'PWD': '/builds/slave/test', 15:31:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:35 INFO - 'SHELL': '/bin/bash', 15:31:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWVdRKrzpQ/Listeners', 15:31:35 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 15:31:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:35 INFO - 'USER': 'cltbld', 15:31:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:35 INFO - 'XPC_FLAGS': '0x0', 15:31:35 INFO - 'XPC_SERVICE_NAME': '0', 15:31:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:35 INFO - Downloading/unpacking psutil>=3.1.1 15:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:31:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:31:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:31:39 INFO - Installing collected packages: psutil 15:31:39 INFO - Running setup.py install for psutil 15:31:39 INFO - building 'psutil._psutil_osx' extension 15:31:39 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 15:31:39 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 15:31:39 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 15:31:39 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 15:31:39 INFO - building 'psutil._psutil_posix' extension 15:31:39 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 15:31:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:31:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:31:40 INFO - ^ 15:31:40 INFO - 1 warning generated. 15:31:40 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 15:31:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:31:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:31:40 INFO - Successfully installed psutil 15:31:40 INFO - Cleaning up... 15:31:40 INFO - Return code: 0 15:31:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f65a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5d0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe722dbcd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46ed30>, '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 0x10f294db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f466b20>, '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.By3fmIZHpj/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.DWVdRKrzpQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 15:31:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:31:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By3fmIZHpj/Render', 15:31:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:40 INFO - 'HOME': '/Users/cltbld', 15:31:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:40 INFO - 'LOGNAME': 'cltbld', 15:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:40 INFO - 'MOZ_NO_REMOTE': '1', 15:31:40 INFO - 'NO_EM_RESTART': '1', 15:31:40 INFO - 'PAGER': '/bin/cat', 15:31:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:40 INFO - 'PWD': '/builds/slave/test', 15:31:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:40 INFO - 'SHELL': '/bin/bash', 15:31:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWVdRKrzpQ/Listeners', 15:31:40 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 15:31:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:40 INFO - 'USER': 'cltbld', 15:31:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:40 INFO - 'XPC_FLAGS': '0x0', 15:31:40 INFO - 'XPC_SERVICE_NAME': '0', 15:31:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:31:43 INFO - Installing collected packages: mozsystemmonitor 15:31:43 INFO - Running setup.py install for mozsystemmonitor 15:31:43 INFO - Successfully installed mozsystemmonitor 15:31:43 INFO - Cleaning up... 15:31:43 INFO - Return code: 0 15:31:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:31:43 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')]} 15:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:43 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')]} 15:31:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:43 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 0x10f65a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5d0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe722dbcd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46ed30>, '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 0x10f294db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f466b20>, '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.By3fmIZHpj/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.DWVdRKrzpQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 15:31:43 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 15:31:43 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 15:31:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By3fmIZHpj/Render', 15:31:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:43 INFO - 'HOME': '/Users/cltbld', 15:31:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:43 INFO - 'LOGNAME': 'cltbld', 15:31:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:43 INFO - 'MOZ_NO_REMOTE': '1', 15:31:43 INFO - 'NO_EM_RESTART': '1', 15:31:43 INFO - 'PAGER': '/bin/cat', 15:31:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:43 INFO - 'PWD': '/builds/slave/test', 15:31:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:43 INFO - 'SHELL': '/bin/bash', 15:31:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWVdRKrzpQ/Listeners', 15:31:43 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 15:31:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:43 INFO - 'USER': 'cltbld', 15:31:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:43 INFO - 'XPC_FLAGS': '0x0', 15:31:43 INFO - 'XPC_SERVICE_NAME': '0', 15:31:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:43 INFO - Downloading/unpacking jsonschema==2.5.1 15:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:31:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:31: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 15:31: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 15:31: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 15:31: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 15:31: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 15:31: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 15:31:46 INFO - Downloading functools32-3.2.3-2.tar.gz 15:31:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:31:46 INFO - warning: no files found matching '*.txt' 15:31:46 INFO - no previously-included directories found matching 'build' 15:31:46 INFO - no previously-included directories found matching 'dist' 15:31:46 INFO - no previously-included directories found matching '.git*' 15:31:46 INFO - Installing collected packages: jsonschema, functools32 15:31:46 INFO - Running setup.py install for functools32 15:31:46 INFO - warning: no files found matching '*.txt' 15:31:46 INFO - no previously-included directories found matching 'build' 15:31:46 INFO - no previously-included directories found matching 'dist' 15:31:46 INFO - no previously-included directories found matching '.git*' 15:31:46 INFO - Successfully installed jsonschema functools32 15:31:46 INFO - Cleaning up... 15:31:46 INFO - Return code: 0 15:31:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:31: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')]} 15:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31: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')]} 15:31:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31: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 0x10f65a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5d0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe722dbcd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46ed30>, '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 0x10f294db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f466b20>, '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.By3fmIZHpj/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.DWVdRKrzpQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 15:31: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 15:31: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 15:31:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By3fmIZHpj/Render', 15:31:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:46 INFO - 'HOME': '/Users/cltbld', 15:31:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:46 INFO - 'LOGNAME': 'cltbld', 15:31:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:46 INFO - 'MOZ_NO_REMOTE': '1', 15:31:46 INFO - 'NO_EM_RESTART': '1', 15:31:46 INFO - 'PAGER': '/bin/cat', 15:31:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:46 INFO - 'PWD': '/builds/slave/test', 15:31:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:46 INFO - 'SHELL': '/bin/bash', 15:31:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWVdRKrzpQ/Listeners', 15:31:46 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 15:31:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:46 INFO - 'USER': 'cltbld', 15:31:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:46 INFO - 'XPC_FLAGS': '0x0', 15:31:46 INFO - 'XPC_SERVICE_NAME': '0', 15:31:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:31:47 INFO - Cleaning up... 15:31:47 INFO - Return code: 0 15:31:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:31:47 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')]} 15:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:47 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')]} 15:31:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:47 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 0x10f65a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5d0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe722dbcd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46ed30>, '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 0x10f294db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f466b20>, '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.By3fmIZHpj/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.DWVdRKrzpQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 15:31:47 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 15:31:47 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 15:31:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By3fmIZHpj/Render', 15:31:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:47 INFO - 'HOME': '/Users/cltbld', 15:31:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:47 INFO - 'LOGNAME': 'cltbld', 15:31:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:47 INFO - 'MOZ_NO_REMOTE': '1', 15:31:47 INFO - 'NO_EM_RESTART': '1', 15:31:47 INFO - 'PAGER': '/bin/cat', 15:31:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:47 INFO - 'PWD': '/builds/slave/test', 15:31:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:47 INFO - 'SHELL': '/bin/bash', 15:31:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWVdRKrzpQ/Listeners', 15:31:47 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 15:31:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:47 INFO - 'USER': 'cltbld', 15:31:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:47 INFO - 'XPC_FLAGS': '0x0', 15:31:47 INFO - 'XPC_SERVICE_NAME': '0', 15:31:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:47 INFO - Downloading/unpacking blobuploader==1.2.4 15:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:49 INFO - Downloading blobuploader-1.2.4.tar.gz 15:31:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:31:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:31: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 15:31: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 15:31: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 15:31: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 15:31: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 15:31: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 15:31:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:31:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:31: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 15:31: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 15:31: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 15:31: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 15:31: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 15:31: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 15:31:51 INFO - Downloading docopt-0.6.1.tar.gz 15:31:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:31:51 INFO - Installing collected packages: blobuploader, requests, docopt 15:31:51 INFO - Running setup.py install for blobuploader 15:31:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:31:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:31:51 INFO - Running setup.py install for requests 15:31:51 INFO - Running setup.py install for docopt 15:31:52 INFO - Successfully installed blobuploader requests docopt 15:31:52 INFO - Cleaning up... 15:31:52 INFO - Return code: 0 15:31:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:52 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 0x10f65a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5d0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe722dbcd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46ed30>, '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 0x10f294db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f466b20>, '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.By3fmIZHpj/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.DWVdRKrzpQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 15:31:52 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 15:31:52 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 15:31:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By3fmIZHpj/Render', 15:31:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:52 INFO - 'HOME': '/Users/cltbld', 15:31:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:52 INFO - 'LOGNAME': 'cltbld', 15:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:52 INFO - 'MOZ_NO_REMOTE': '1', 15:31:52 INFO - 'NO_EM_RESTART': '1', 15:31:52 INFO - 'PAGER': '/bin/cat', 15:31:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:52 INFO - 'PWD': '/builds/slave/test', 15:31:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:52 INFO - 'SHELL': '/bin/bash', 15:31:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWVdRKrzpQ/Listeners', 15:31:52 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 15:31:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:52 INFO - 'USER': 'cltbld', 15:31:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:52 INFO - 'XPC_FLAGS': '0x0', 15:31:52 INFO - 'XPC_SERVICE_NAME': '0', 15:31:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:31:52 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-JETWMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:31:52 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-XfIFj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:31:52 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-_bCpKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:31:52 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-no8s_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:31:52 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-MUJIBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:31:52 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-YawaMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:31:53 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-9ZueT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:31:53 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-Xnbnci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:31:53 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-wCTJOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:31:53 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-6rgYCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:31:53 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-ieYsCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:31:53 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-9gI0ba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:31:53 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-lzhyA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:31:53 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-MTEcKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:31:53 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-TE8FA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:31:53 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-rn9v0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:31:54 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-VMKgUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:31:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:31:54 INFO - Running setup.py install for manifestparser 15:31:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:31:54 INFO - Running setup.py install for mozcrash 15:31:54 INFO - Running setup.py install for mozdebug 15:31:54 INFO - Running setup.py install for mozdevice 15:31:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:31:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:31:54 INFO - Running setup.py install for mozfile 15:31:54 INFO - Running setup.py install for mozhttpd 15:31:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:31:55 INFO - Running setup.py install for mozinfo 15:31:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:31:55 INFO - Running setup.py install for mozInstall 15:31:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:31:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:31:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:31:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:31:55 INFO - Running setup.py install for mozleak 15:31:55 INFO - Running setup.py install for mozlog 15:31:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:31:55 INFO - Running setup.py install for moznetwork 15:31:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:31:55 INFO - Running setup.py install for mozprocess 15:31:55 INFO - Running setup.py install for mozprofile 15:31:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:31:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:31:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:31:56 INFO - Running setup.py install for mozrunner 15:31:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:31:56 INFO - Running setup.py install for mozscreenshot 15:31:56 INFO - Running setup.py install for moztest 15:31:56 INFO - Running setup.py install for mozversion 15:31:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:31:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:31:56 INFO - Cleaning up... 15:31:56 INFO - Return code: 0 15:31:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:31: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')]} 15:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31: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')]} 15:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31: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 0x10f65a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5d0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe722dbcd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46ed30>, '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 0x10f294db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f466b20>, '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.By3fmIZHpj/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.DWVdRKrzpQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 15:31: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 15:31: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 15:31:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By3fmIZHpj/Render', 15:31:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:56 INFO - 'HOME': '/Users/cltbld', 15:31:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:56 INFO - 'LOGNAME': 'cltbld', 15:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:56 INFO - 'MOZ_NO_REMOTE': '1', 15:31:56 INFO - 'NO_EM_RESTART': '1', 15:31:56 INFO - 'PAGER': '/bin/cat', 15:31:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:56 INFO - 'PWD': '/builds/slave/test', 15:31:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:56 INFO - 'SHELL': '/bin/bash', 15:31:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWVdRKrzpQ/Listeners', 15:31:56 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 15:31:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:56 INFO - 'USER': 'cltbld', 15:31:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:56 INFO - 'XPC_FLAGS': '0x0', 15:31:56 INFO - 'XPC_SERVICE_NAME': '0', 15:31:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:31:57 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-z7itkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:31:57 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)) 15:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:31:57 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-K3vKOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:31:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 15:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:31:57 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-XzcGkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:31:57 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)) 15:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:31:57 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-nlcp1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:31:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 15:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:31:57 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-arEGEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:31:57 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)) 15:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:31:57 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-1mUvME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:31:57 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)) 15:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:31:57 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-WWUhNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:31: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)) 15:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:31:57 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-lvW0zg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:31: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)) 15:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:31:57 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-ecATeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:31: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)) 15:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:31:58 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-IGgWF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:31:58 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)) 15:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:31:58 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-9NVFT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:31:58 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)) 15:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:31:58 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-JIjm0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:31:58 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-bZMSgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:31:58 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)) 15:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:31:58 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-2pSi7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 15:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:31:58 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-Qw6T5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:31:58 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)) 15:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:31:58 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-ZFnysC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:31:58 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-ybui4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:31: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)) 15:31: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==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:31: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==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:31: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)) 15:31: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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:31: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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:31:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:31: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 15:31: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 15:31: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 15:31: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 15:31: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 15:31: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 15:32:01 INFO - Downloading blessings-1.6.tar.gz 15:32:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:32:01 INFO - Installing collected packages: blessings 15:32:01 INFO - Running setup.py install for blessings 15:32:01 INFO - Successfully installed blessings 15:32:01 INFO - Cleaning up... 15:32:01 INFO - Return code: 0 15:32:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:32:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:32:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:32:02 INFO - Reading from file tmpfile_stdout 15:32:02 INFO - Current package versions: 15:32:02 INFO - blessings == 1.6 15:32:02 INFO - blobuploader == 1.2.4 15:32:02 INFO - docopt == 0.6.1 15:32:02 INFO - functools32 == 3.2.3-2 15:32:02 INFO - jsonschema == 2.5.1 15:32:02 INFO - manifestparser == 1.1 15:32:02 INFO - mozInstall == 1.12 15:32:02 INFO - mozcrash == 0.17 15:32:02 INFO - mozdebug == 0.1 15:32:02 INFO - mozdevice == 0.48 15:32:02 INFO - mozfile == 1.2 15:32:02 INFO - mozhttpd == 0.7 15:32:02 INFO - mozinfo == 0.9 15:32:02 INFO - mozleak == 0.1 15:32:02 INFO - mozlog == 3.4 15:32:02 INFO - moznetwork == 0.27 15:32:02 INFO - mozprocess == 0.23 15:32:02 INFO - mozprofile == 0.28 15:32:02 INFO - mozrunner == 6.12 15:32:02 INFO - mozscreenshot == 0.1 15:32:02 INFO - mozsystemmonitor == 0.3 15:32:02 INFO - moztest == 0.7 15:32:02 INFO - mozversion == 1.4 15:32:02 INFO - psutil == 3.1.1 15:32:02 INFO - requests == 1.2.3 15:32:02 INFO - wsgiref == 0.1.2 15:32:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:32:02 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')]} 15:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:02 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')]} 15:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:03 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 0x10f65a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5d0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe722dbcd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46ed30>, '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 0x10f294db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f466b20>, '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.By3fmIZHpj/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.DWVdRKrzpQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 15:32:03 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 15:32:03 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 15:32:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By3fmIZHpj/Render', 15:32:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:03 INFO - 'HOME': '/Users/cltbld', 15:32:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:03 INFO - 'LOGNAME': 'cltbld', 15:32:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:03 INFO - 'MOZ_NO_REMOTE': '1', 15:32:03 INFO - 'NO_EM_RESTART': '1', 15:32:03 INFO - 'PAGER': '/bin/cat', 15:32:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:32:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:03 INFO - 'PWD': '/builds/slave/test', 15:32:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:03 INFO - 'SHELL': '/bin/bash', 15:32:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWVdRKrzpQ/Listeners', 15:32:03 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 15:32:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:03 INFO - 'USER': 'cltbld', 15:32:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:03 INFO - 'XPC_FLAGS': '0x0', 15:32:03 INFO - 'XPC_SERVICE_NAME': '0', 15:32:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:32:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:03 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)) 15:32:03 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)) 15:32:03 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)) 15:32:03 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)) 15:32:03 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)) 15:32:03 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)) 15:32:03 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)) 15:32:03 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)) 15:32:03 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)) 15:32:03 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)) 15:32:03 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)) 15:32:03 INFO - Cleaning up... 15:32:03 INFO - Return code: 0 15:32:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:32:03 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')]} 15:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:03 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')]} 15:32:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:03 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 0x10f65a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5d0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe722dbcd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46ed30>, '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 0x10f294db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f466b20>, '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.By3fmIZHpj/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.DWVdRKrzpQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 15:32:03 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 15:32:03 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 15:32:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By3fmIZHpj/Render', 15:32:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:03 INFO - 'HOME': '/Users/cltbld', 15:32:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:03 INFO - 'LOGNAME': 'cltbld', 15:32:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:03 INFO - 'MOZ_NO_REMOTE': '1', 15:32:03 INFO - 'NO_EM_RESTART': '1', 15:32:03 INFO - 'PAGER': '/bin/cat', 15:32:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:32:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:03 INFO - 'PWD': '/builds/slave/test', 15:32:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:03 INFO - 'SHELL': '/bin/bash', 15:32:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWVdRKrzpQ/Listeners', 15:32:03 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 15:32:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:03 INFO - 'USER': 'cltbld', 15:32:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:03 INFO - 'XPC_FLAGS': '0x0', 15:32:03 INFO - 'XPC_SERVICE_NAME': '0', 15:32:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:32:04 INFO - Cleaning up... 15:32:04 INFO - Return code: 0 15:32:04 INFO - Running post-action listener: _resource_record_post_action 15:32:04 INFO - Running post-action listener: _start_resource_monitoring 15:32:04 INFO - Starting resource monitoring. 15:32:04 INFO - [mozharness: 2016-10-01 22:32:04.103442Z] Finished create-virtualenv step (success) 15:32:04 INFO - [mozharness: 2016-10-01 22:32:04.104449Z] Running install step. 15:32:04 INFO - Running pre-action listener: _resource_record_pre_action 15:32:04 INFO - Running main action method: install 15:32:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:32:04 INFO - Reading from file tmpfile_stdout 15:32:04 INFO - Detecting whether we're running mozinstall >=1.0... 15:32:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:32:04 INFO - Reading from file tmpfile_stdout 15:32:04 INFO - Output received: 15:32:04 INFO - Usage: mozinstall [options] installer 15:32:04 INFO - Options: 15:32:04 INFO - -h, --help show this help message and exit 15:32:04 INFO - -d DEST, --destination=DEST 15:32:04 INFO - Directory to install application into. [default: 15:32:04 INFO - "/builds/slave/test"] 15:32:04 INFO - --app=APP Application being installed. [default: firefox] 15:32:04 INFO - mkdir: /builds/slave/test/build/application 15:32:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:32:38 INFO - Reading from file tmpfile_stdout 15:32:38 INFO - Output received: 15:32:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:32:38 INFO - Running post-action listener: _resource_record_post_action 15:32:38 INFO - [mozharness: 2016-10-01 22:32:38.689568Z] Finished install step (success) 15:32:38 INFO - [mozharness: 2016-10-01 22:32:38.689743Z] Running run-tests step. 15:32:38 INFO - Running pre-action listener: _resource_record_pre_action 15:32:38 INFO - Running main action method: run_tests 15:32:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:32:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:32:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:32:38 INFO - 2016-10-01 15:32:38.704 screenresolution[1643:7637] starting screenresolution argv=screenresolution get 15:32:38 INFO - 2016-10-01 15:32:38.706 screenresolution[1643:7637] Display 0: 1600x1200x32@60 15:32:38 INFO - 2016-10-01 15:32:38.712 screenresolution[1644:7639] starting screenresolution argv=screenresolution list 15:32:38 INFO - Available Modes on Display 0 15:32:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:32:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:32:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:32:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:32:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:32:38 INFO - Intel Iris: 15:32:38 INFO - Chipset Model: Intel Iris 15:32:38 INFO - Type: GPU 15:32:38 INFO - Bus: Built-In 15:32:38 INFO - VRAM (Dynamic, Max): 1536 MB 15:32:38 INFO - Vendor: Intel (0x8086) 15:32:38 INFO - Device ID: 0x0a2e 15:32:38 INFO - Revision ID: 0x0009 15:32:38 INFO - Displays: 15:32:38 INFO - Display: 15:32:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:32:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:32:38 INFO - Display Serial Number: 1600x1200 60 15:32:38 INFO - Main Display: Yes 15:32:38 INFO - Mirror: Off 15:32:38 INFO - Online: Yes 15:32:38 INFO - Rotation: Supported 15:32:38 INFO - Return code: 0 15:32:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:32:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:32:38 INFO - Python 2.7.3 15:32:38 INFO - Return code: 0 15:32:38 INFO - grabbing minidump binary from tooltool 15:32: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')]} 15:32:38 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 0x10f46ed30>, '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 0x10f294db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f466b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:32:38 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 15:32:38 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 15:32:38 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 15:32:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:32:39 INFO - Return code: 0 15:32:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:32:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:32:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:32:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:32:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:32:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0208', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:32:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0208 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:32:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By3fmIZHpj/Render', 15:32:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:39 INFO - 'HOME': '/Users/cltbld', 15:32:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:39 INFO - 'LOGNAME': 'cltbld', 15:32:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:32:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:39 INFO - 'MOZ_NO_REMOTE': '1', 15:32:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:32:39 INFO - 'NO_EM_RESTART': '1', 15:32:39 INFO - 'PAGER': '/bin/cat', 15:32:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:39 INFO - 'PWD': '/builds/slave/test', 15:32:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:32:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:39 INFO - 'SHELL': '/bin/bash', 15:32:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWVdRKrzpQ/Listeners', 15:32:39 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 15:32:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:39 INFO - 'USER': 'cltbld', 15:32:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:39 INFO - 'XPC_FLAGS': '0x0', 15:32:39 INFO - 'XPC_SERVICE_NAME': '0', 15:32:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:32:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0208', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:32:39 INFO - mozversion application_buildid: 20161001140155 15:32:39 INFO - mozversion application_changeset: 7c576fe3279d87543f0a03b844eba7bc215e17f1 15:32:39 INFO - mozversion application_display_name: Nightly 15:32:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:32:39 INFO - mozversion application_name: Firefox 15:32:39 INFO - mozversion application_remotingname: firefox 15:32:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:32:39 INFO - mozversion application_vendor: Mozilla 15:32:39 INFO - mozversion application_version: 52.0a1 15:32:39 INFO - mozversion platform_buildid: 20161001140155 15:32:39 INFO - mozversion platform_changeset: 7c576fe3279d87543f0a03b844eba7bc215e17f1 15:32:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:32:39 INFO - mozversion platform_version: 52.0a1 15:32:39 INFO - using testdate: 1475361159 15:32:39 INFO - actual date: 1475361159 15:32:39 INFO - starting webserver on 'localhost:49271' 15:32:39 INFO - SUITE-START | Running 2 tests 15:32:39 INFO - TEST-START | dromaeo_css 15:32:39 INFO - operating with platform_type : mac_ 15:32:39 INFO - Initialising browser for dromaeo_css test... 15:32:39 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/tmpUePNAY/profile http://localhost:49271/getInfo.html) 15:32:41 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 15:32:41 INFO - PROCESS | 1654 | colorDepth:24 15:32:41 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 15:32:41 INFO - PROCESS | 1654 | __metrics 15:32:41 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:32:41 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:32:42 INFO - TEST-INFO | 1654: exit 0 15:32:42 INFO - Browser initialized. 15:32:42 INFO - Running cycle 1/1 for dromaeo_css test... 15:32:42 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/tmpUePNAY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:32:54 INFO - PROCESS | 1656 | RSS: Main: 236441600 15:32:54 INFO - PROCESS | 1656 | 15:33:45 INFO - PROCESS | 1656 | 1475361225949 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:35:57 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 15:36:00 INFO - PROCESS | 1656 | RSS: Main: 489476096 15:36:00 INFO - PROCESS | 1656 | 15:39:03 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 15:39:03 INFO - PROCESS | 1656 | RSS: Main: 372977664 15:39:03 INFO - PROCESS | 1656 | 15:42:06 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 15:42:06 INFO - PROCESS | 1656 | RSS: Main: 340123648 15:42:06 INFO - PROCESS | 1656 | 15:45:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 15:45:09 INFO - PROCESS | 1656 | RSS: Main: 350531584 15:45:09 INFO - PROCESS | 1656 | 15:48:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 15:48:13 INFO - PROCESS | 1656 | RSS: Main: 331657216 15:48:13 INFO - PROCESS | 1656 | 15:51:17 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 15:51:17 INFO - PROCESS | 1656 | RSS: Main: 503799808 15:51:17 INFO - PROCESS | 1656 | 15:51:17 INFO - PROCESS | 1656 | __start_tp_report 15:51:17 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 15:51:17 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 15:51:17 INFO - PROCESS | 1656 | |i|pagename|runs| 15:51:17 INFO - PROCESS | 1656 | |0;dojo.html;3272.051105179498;3291.1278511194537;3304.702576768092;3325.9523250176208;3347.698707116359;4797.96843678609;5488.204210389491;5591.720413979305;5611.607187496877;5628.971855140729;4990.401151861782;5537.750801213945;5550.583706222029;5658.43415658434;5663.7168141592965;5049.368828896388;5056.696598204101;5093.847184584469;5115.820946266881;5135.717535535544;5141.357330333708;5176.5082317179995;5199.272101905736;5204.739763011853;5224.164133738603;5058.924116138261;5118.6416950813455;5161.019406312788;5186.585073194155;5224.764885580158;5105.33630628016;5126.641135120543;5126.974365128188;5130;5130.230465430178;5066.138756411419;5104.464031276737;5111.948880511184;5120.001599688062;5123.974380128113;5576.9163462548095;5577.860553486195;5589.385167631557;5597.076482380402;5611.382747897728;5039.244113382985;6029.487493563009;6056.636601803906;6091.375591859004;6133.457080270857;6106.339326077114;6158.8152355428965;6178.752849885999;6179.165812615284;6220.066989951498;5669.744861481243;5694.288213973254;5694.515966142924;5701.800436984686;5705.62913410627;6115.757578773014;6144.402455361638;6191.81424557264;6195.380461953769;6270.372962703693;5961.5230781537375;5962.22491076166;5966.194563733927;5972.611780234271;5983.311919129269;6082.939170608237;6150.169727086786;6153.4154255345675;6154.446099851035;6155.353687862799;5918.556108291896;5926.7925622603;5945.554083443672;6026.879462410728;6096.0855871619615;6032.819015429544;6060.151578778975;6062.969685151546;6086.1175129606845;6099.451049405575;4842.564169224786;4847.927281090787;4853.320535125014;4858.951410485921;4891.217405215149;4339.544347843431;4343.9348409773875;4364.214441400578;4408.713433626804;4431.667624928144;5488.506034456918;5554.555635549146;5560.443955604488;5572.108462646038;5598.048331783607;6102.852830488404;6115.541334399938;6128.295246046672;6134.9693251534345;6143.784967526204;5623.578231632644;5679;5684.090545512699;5691.203231547586;5702.800401985911;5272.623110070475;5327.280817089643;5331.120365139786;5333.333333333333;5348.625596208306;6327.683615819191;6357.237131544244;6364.1408409865;6366.204224471941;6376.234851817719;6030.698465076816;6100.511959043355;6106.755729770759;6134.355892631388;6159.630422174684;6250.781222657185;6298.779542715983;6330.9050364245495;6338;6364.395382438846;6054.939450605438;6056.818295451144;6062.7271772769445;6068.666223357758;6076.270847498328;5716.79991200306;5746;5771.740271687868;5807.186993820953;6156.78451254204;5620.74706638194;5629.127485239795;5637.661740295432;5638.323401191883;5649.548036157015;5625;5631.380548139619;5636.985342638364;5637.49262566371;5648.2092507049865;5322.138639165901;5532.031894418541;5549.417311182429;5549.5837812165055;5552.416996215501;5551.583631227755;5617.297837770278;5621.550275977994;5627.774889004394;5634.661920284796;5735.7705691773535;5764.308283006066;5798.507126894093;5843.678597677169;5849 15:51:17 INFO - PROCESS | 1656 | |1;ext.html;493.8814684475727;522.581934452438;522.6158773301623;526.7261024267381;566.1309889319895;30392;41741.37387939183;42518.362224566656;42704;45196.54803451965;44071.77964110175;44172.55827441725;44302.33546496797;44361.778191109;44592.55407445924;9693.806123877535;9887.258455615822;9894.950525247365;9905.603775848957;9907.900920990805;8695.69565065223;8930;8935.910640893573;9000.189982901571;9007.234385077243;11911.04711623068;12209.938950305235;12220.327881966488;12349.382530873465;12356.258624482514;12202.572909948154;12324.938375308066;12325.198862073936;12334.81497777534;12343.69140771488;8714.433561818461;8942.59758310871;11694.707632309175;11902;11988.340641264727;39886.80056599728;42394.364084538756;42415.57584424178;42438.1512369754;42486.57513424857;3387.8644854205804;3541.769784963969;3567.8572857085687;3937.310970580137;3959.8614048508166;30456.69543304583;31692.049238522883;31876.20309492258;31923.680763192304;32177.839110804296;3218.1954511372155;3293.75296852737;3309.3381323735107;3335.2328964338094;3387.8644854205804;482852.9999999965;487560;491777;492108;495136;59280.70359648261;59597.70201149053;59708;59765.70117149386;59800;64823.02765458522;64825;64881;64903.675481623235;64949.67525162344;16187.352505899868;16426.42507512231;16445.506634800975;16451.341946322013;16474.341026359052;10534.36793792375;12602.747945041048;12606.81089783654;12612.747745045048;12622.990160787114;15703.900726949232;15976.121313327878;17827.554311142325;17829.821701783076;18361.3573524926;5594.300712410949;6604.447954730635;6632.668366581652;6653.567518111307;6678.265390807007;905.248643625789;925.847235206197;933.4772527384687;970.8980307351067;1042.2547878266923;20800.583988320148;29946.25134371658;30030.69969300279;30597.235069123006;30825;1057.1225882517492;1066.7866426714688;1100.0539535999035;1113.6046703420332;1133.1274918312854;15384.30770615325;15661.686766264611;15688.450904218298;15980.76028859568;16193.514194574182;546467;651348;653366.7331663311;655323;656515;57534.8493030137;57708;57743.8451230973;57798.7110064447;57828.13257801136;1343.1135450602555;1390.082545519952;1405.6977749783846;1407.0643022389995;1411.386047069528;26525.734742652327;29371.41257174887;29633.703662963093;30331.39337213199;30672.35891023707;51223.48776512187;51545.71135721487;51629.48370516247;51690.22464663108;51719.482805171465;585245;587997;589474;589697;589977;33921;41394.793026035884;41600.79199603983;41731;42843.57156428521;47862;47957.801054973905;48097.03805923932;48944.77638058935;49010.75494622504;31450.528242076845;37581;39509.80245098871;39702.801485992386;39783.60216397915;32506.83746581252;39090.80454597822;39365.62220322275;39808;40446.1910761789;36697;37363.81318093501;37957;38088.809555952044;38205.80897095497;33826.27910929845;34176.48735269023;34788.82605586956;34843.65156348404;35136.94589162329;25295.620565691162;25369.74630253674;25585;25701.228963131136;25966.48067038611 15:51:17 INFO - PROCESS | 1656 | |2;jquery.html;355.1883945185249;372.6515707813195;374.76764406068236;381.95416550014005;401.22563452536593;15300.54098377049;15453.15007674579;17124.82875171248;20506;21162.047707853144;20186.697199542017;20291.59416811663;20773.792262077375;20942.685859712117;21118.683219751714;5921.407859214066;6055.666938318391;6056.000759874615;6215.067739839038;6236;5969.253843269591;6118.541109416789;6165.0752387142065;6173.074038894157;6176.691165441733;4167.354060120671;4405.493368262659;6395.648239346834;6705.564138331018;6757;5784.161296611967;6115.694215289218;6804.55770374924;6808.21705503866;6829.3170682931805;5970.194023806817;5975.551833612496;5980.521558275327;5982.8504287392725;5997.700114994276;13549.59351219465;25057.997680092776;25311.493770124493;25366.858491367933;25467.363315917064;2299.2872209615075;2349.2247558305717;2358.917437889683;2414.5653782319177;2489.0417189382038;9643.951780241054;9808.754781130458;9895;10381.013803688638;10419.270651054429;1833.5232839461837;1875.5967466994662;1880.482867211514;1891.6878715012006;1900.828925396721;112106;117595.41202294019;119268.40365798202;121585;125143.37428312891;9041.954790226007;10579.735506612396;10920.235583509078;11119.221654484109;11122.165837562216;14118.223497707519;14125.223112728692;14141.010129291057;14149.938754593446;14177.936654750934;7990.4007199460275;8252.628631711588;8345.332373410112;8378.078411374743;8406.201410865959;8190.058143313476;8196.303314218358;8205.261526462647;8295.336373090138;8366.665333386718;4381.78091095444;4382.05785756064;4382.69321147523;4393.648508119343;4471.8434854779925;1517.855803698647;1601.4635097242553;1641.7455294429383;1827.8575890068707;1891.8770279932035;788.7441916119892;845.7716416567492;873.5675840459013;878.1920633017639;885.6103314541581;20015;21051.157953681683;21142.154313827275;21229.68155477669;21262.149514019577;796.6853093028221;900.5812297281665;905.805251870851;908.4821651658623;916.9083091690777;10736.409497477558;11013.83479247812;11050.834237486444;11229.66311010671;11253.606123785628;561303;566875.1593694201;572504;574043;578738;65635.67182164155;67710.61446927732;68061;122727.77272227342;126964;941.317544780029;956.1872408452759;999.9550020249106;1003.0821798054699;1004.42747633848;16589.17054147312;18220.271189152816;18514.351997680016;18766.343177988703;18780.812191877907;50489.74755126201;51724;52143.47856521386;52736;53102;61043.81127604203;73412;74066.62966685132;74093;75224;7642.5032372895585;7678.308952194329;7912.0901096374655;7983.84032319362;7987.760367188994;10671.413072280788;12348;12467.189632673848;12644.936775316064;12648.241105533698;10722.249442538947;11014;11049.447527623426;11187.72030699239;11227.800076978023;7122.7507037253445;10633.468326583794;10824.134069274282;10849.294795838245;11017.338959662446;7825.5131525010065;7839.568823714522;7861.731719147568;7934.682612695427;8177.100518942792;11803.763924721628;11986.520539178684;12286.201396909173;12324.691882703004;12333.93833030829;11622.94188529052;11648.88351116478;11753.236039657395;11880.821787673365;12124.454399552218 15:51:17 INFO - PROCESS | 1656 | |3;mootools.html;1529.2659523428758;1540.5686407805815;1748.6415284866603;1846.6768315544775;1905.333133403309;8265.380096492765;8733.083026282236;8733.214010739031;8736.519491427976;8739.606717697703;7746.496477728943;7993.1607181246;8007.079185893624;8330.375221858354;8528.872066919;2302.689136966513;2342.4846533762543;2344.0975224538506;2345.95308093838;2350.8942097605564;1945.5136215946013;2317.756635553268;2336.158982766203;2367.8934447949796;2369.4668699542553;2264.864108153516;2268.330842401487;2275.590393729128;2277.738060123082;2363.6927199464044;2325.1513197682807;2327.1156960355042;2331.941701457477;2334.334714606329;2336.0655737704883;2234;2326.8836558172025;2331.1724337860155;2333.5099629077913;2335.6029474989123;8663.956680216621;8724.083971182998;8729.912700872974;8733.563321833946;8736;5109.718965456898;5281.102212623825;5285.814996475144;5286.365636123652;5290.9735451322495;3429.9828500857334;3496.807675577842;3619.7647152934974;3653.6346365363515;3662.7252956028406;1073.2755390111722;1080.827067669177;1082.3181395720724;1102.2890235797865;1102.6581759654532;11337.979581837591;11497.310161390344;11549;11555.422228888521;11556.422178891022;4485.932711009337;4520.660950428731;4711.952880471152;4718.245080787057;4720.197566413718;9726.659566915125;9730.659426920165;9736.026397360207;9747.951260243653;9785.608575656894;5254.65844720092;5278.20826875964;5279;5281.709505977208;5284.682919024871;4315.719478233905;4351.69538132334;4521.525239849769;4847.224444088929;4855.004724031635;3261.510773383964;3725.9813700931863;3786.450964610171;3873.86441474547;3883.0680636647003;1243.738814848892;1260.6785269756338;1263.564070395712;1270.30133426615;1272.7581759465672;711.7224282529818;760.8326168243088;789.2344425906862;791.3618809065294;902.8916530016309;5040.49595040493;5522.447755224526;5755.59710820247;5768.2501274834;5839.795607153729;744.8547533231076;863.6329559937002;939.6100618243353;941.7316064921464;949.5442187749919;3719.2189640175325;3773.8301776420126;3777.338965680995;3885.611438856148;3992.9201415972097;10243.743906402251;10569.260151789455;10581.89418105809;10600.04599586041;10611.204159688054;10091.37624217694;10485.475726213659;10566.947165264124;10612.946935265429;10619.893801062046;7887.9605601971625;8008.639611217509;8076.232757888109;8096.02847658273;8102.554359510165;5730.713464326684;5739.196512488172;5759.94240057594;5936.257967754031;6083.6349819011;9816.80366392654;9913.801723965624;9958.452285124387;9962.203023758228;9963.750906227402;9616.471094089613;9638.855417168608;9893.406395616286;10139.087482126644;10152.39085654863;6829.726810927706;7083.716651333889;7372.889406659012;7533.811654708456;7542.321191092828;7855;8220.794480138044;8241.340692744687;8302.169783021649;8304.169583041648;7302.525335853153;7403.7408690695565;7428.220036896048;7502.699892004259;7606.125295590857;7224.602646854473;7239.8914016290855;7241.927580724336;7250.238724934017;7349.55902645843;6777.390034896883;6806.523543351917;6904.275051119761;6917.550359226634;6920.377166055079;4759.738214398241;4814.422269327703;4840.903181936271;4878.609711223165;4981.676191047571;4743;4850.878728031827;4950.6782059166035;5002.924956125588;5032.6980381177245 15:51:17 INFO - PROCESS | 1656 | |4;prototype.html;265.697105299958;296.98515074246285;298.5491907220098;310.08679439051986;314.68845842615815;5000.874978125548;5194.27280180775;5242.737863106844;5289.947100528999;5339.6893925766035;4539.636829053676;4704.364910737049;5054.671446355985;5093.173288824701;5177.534021938025;3634.67287944085;3985.8405663773415;3989.601039896016;4007.537248904144;4019.919601607966;3717.4609681596153;3849.940281949158;4001.1197536541913;4031.5363733170766;4051.493563304587;3645.76302540334;4020.1758639478985;4023.9537720192816;4025.75845449274;4047.8785636430953;3593.13764696471;3952.4664170336923;3975.6819454443867;3977.960220397788;3997.660198883084;2566.371239046427;2958.6390260480207;3112.7354174895163;3411.6076651184935;3490.9701638016845;4211.599898009684;4648.83729069481;4997.925031124536;5040.924386134211;5059.848204553869;3796.7152463565067;4002.2995975704134;4481.887952801173;4549;4586.54134586542;4509.526499717515;5232.947670523284;5464.726763661841;5486.972565137189;5541.6674999499755;3286.997465772938;3729.664330210294;3739.1960728443514;3758.5113935188524;3822.617738226183;5624.762552238501;5655.575831812589;5667.376588575212;5698.886022279531;5704.686242256675;3669.9816500917323;3813.7139714520968;3822.369309063973;3836.9616303836606;3838.654521093059;3325.935700575841;3723.2739615774663;3843.2889915365186;3858.344081506151;3877.0695033191832;3793.6775374093513;4456.309272062771;4471.6869819113;4476.865694029184;4517.051419201939;4555.498895121534;4832.347633069561;5462.562994960394;5497.39528651848;5535.916961245584;1131.8585176852894;1134.9659510214706;1238.3374894431436;1303.7158398977;1315.684235783405;707.2326525719652;726.1286456252518;780.5433821214654;798.3852433626075;811.472542847154;4225.408442818008;4260.70048635163;4267.935980960288;4268.978655106766;4299.54854740254;4197.496300443966;4251.893702657459;4625.583697467244;4630.513796051433;4667.802555249665;3787.7273635818838;3912.960870391317;4191.203671302443;4238.901743636858;4245.957540424556;2175.5866385386726;2199.483121466454;2281.897314620846;2307.4692820651344;2444.633305004228;4237.436351007657;4632.745199014019;4739.625508602536;4743.620510359163;4785.420811132388;4423.911521769546;4668.50980647034;4711.481737008926;4713.9057218856115;4740.146773580788;3976.3632283641723;4646.886585987464;4709.3642358281195;4721.315409265637;4779.330893674888;4657.790399432101;4719.504452032487;4720.386349774507;4733.083101343961;4742.620590352694;3882.8835134946;3905.0042239228815;4158.189153115113;4610.677252592286;4760.404949381327;4277.139444341721;4610.354550363019;4749.786259618257;4797.56821886032;4804.750764801247;2664.427148163154;2889.147701428126;2929.414117176616;3121.4849549824025;3306.933861322712;3999.460072890239;4326.9615292330245;4559.511973340464;4669.112868554964;4685.882852928704;4911.58251548608;5150;5313.582320044447;5355.277037599874;5508.3114610673665;4807.302941073455;5447.3735520414075;5469.718169098509;5482.972585137049;5506.174073888948;4916.934887347251;4938.827141050046;5013.092406404204;5290.391604965478;5381.515663590296;4896.510348965075;5517.531009864207;5533.280673512418;5535.979367530742;5556.777728890799;4384.276594361894;4550.953280745381;5145.665531740425;5425.620206585501;5451.672899626035 15:51:17 INFO - PROCESS | 1656 | |5;yui.html;720.6937051753004;731.8426538294266;754.3248792330864;790.3519114326252;797.9561124138178;15711.842881571181;15996.200189990512;15999.360025598962;16008;16019.919900400497;14353.712925741478;14483.2758362082;14487.92756036221;14504.202268875208;14509.709805803877;673.8147009572382;693.7259782385954;694.7325279767301;697.6372286411063;823.7117009046831;695.6174104242647;698.584342316321;835.7409203147043;861.9870701939476;867.7830542364409;896.1218006353777;901.0718959471722;902.8374892519344;903.5482258870564;906.7461110888962;902.9051949545302;907.0476000199764;907.9273658107336;908.1463424381061;909.2226146644656;529.5684017525742;673.5016088094775;674.3896773420063;675.9864802703919;676.3371895542347;12449.136698559292;14480.493182738554;14990;15033;15357.769633455508;14175.362108705132;14656.926715366355;14833.851661483355;14866.851331486654;14922.850771492363;11835.171537992344;11857.525698972031;11956.70108247292;12050.337231452266;12205.694857628541;3320;3885.1452680410266;3895.941560876589;3922.784246866421;3949.5063117110362;68416.6579167106;88635;92464;98757.50621246919;108841;11017.228793984345;11092.611758588571;11112.944435277772;11177.49701263445;11279.492422840993;21264.25575104864;21391.46521336948;21437.142514299252;21448.463788405414;21471.03380347888;8028.197180281925;8066;8146.389020823462;8191.795205119801;8221.465604735673;4312.663074446931;4339.500957389878;4522.072975040174;4669.299605059268;4676.158291507493;3029.4546981543094;3439.518467414564;3555.6266592007983;3580.785152890835;3587.461880717913;665.9567128136753;671.7111641993989;688.786476192372;699.5592776550741;702.9683664235122;586.9413058694097;593.4273426143706;616.3251239892311;660.9556900097834;673.2022553274386;9122.86315705265;9429.245660347156;9536.713898583053;9663.46850923206;9782.706518804447;622.4677900395187;654.4698793976894;664.2162248546762;684.5345165287553;696.5228818259509;4031.7177797554477;4248.4901811782165;4699.647526435531;4741.336212930192;4823.903521929542;93266.53366733258;129259;129666.35166824295;154587.04358303902;164773.28400739984;137399.31300343433;138550;139534;141826;142914;113509;118023;118286.40856795834;119844.40077799556;121755.39122304332;7477.513961592479;8510.957445212734;9002.864957025518;9083.727488175366;9131.315151363542;64394.03408948963;64630.67684661547;64858.675706621165;66251.4399126323;66538.00192997206;78619.60690196512;88122.55938720265;88250;88721.55639221762;89433.55283223542;12524.686882828002;12803.61589152327;13136.343182840628;13150.408231629408;13605.319734013061;14713.632159196106;14715.488568603329;14849.777253340992;15020.849791502382;15331.156786376856;11989.700257493283;13540.32298385057;13599.274705348906;14009.929950350182;14072.859271407564;13501.077292652044;13622.38699258516;13941.163530188222;13944.651383715489;14361.856381436051;13469.720376564212;13773.380197890918;13988.454290756437;14200.076994995292;14201.644958876112;10273.280870339004;11510;11520.769584608428;11653.941730291293;12212.267263964191;10255.58977640886;10368.792624147625;10369.63706270277;11464.87941526241;11668.766624667629 15:51:17 INFO - PROCESS | 1656 | __end_tp_report 15:51:17 INFO - PROCESS | 1656 | __start_cc_report 15:51:17 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2973 15:51:17 INFO - PROCESS | 1656 | __end_cc_report 15:51:17 INFO - PROCESS | 1656 | __startTimestamp1475362277546__endTimestamp 15:51:17 INFO - PROCESS | 1656 | 15:51:17 INFO - PROCESS | 1656 | ------- Summary: start ------- 15:51:17 INFO - PROCESS | 1656 | Number of tests: 6 15:51:17 INFO - PROCESS | 1656 | 15:51:17 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5585.36 Median:5638.32 stddev:614.95 (10.9%) stddev-sans-first:589.45 15:51:17 INFO - PROCESS | 1656 | Values: 3272.1 3291.1 3304.7 3326.0 3347.7 4798.0 5488.2 5591.7 5611.6 5629.0 4990.4 5537.8 5550.6 5658.4 5663.7 5049.4 5056.7 5093.8 5115.8 5135.7 5141.4 5176.5 5199.3 5204.7 5224.2 5058.9 5118.6 5161.0 5186.6 5224.8 5105.3 5126.6 5127.0 5130.0 5130.2 5066.1 5104.5 5111.9 5120.0 5124.0 5576.9 5577.9 5589.4 5597.1 5611.4 5039.2 6029.5 6056.6 6091.4 6133.5 6106.3 6158.8 6178.8 6179.2 6220.1 5669.7 5694.3 5694.5 5701.8 5705.6 6115.8 6144.4 6191.8 6195.4 6270.4 5961.5 5962.2 5966.2 5972.6 5983.3 6082.9 6150.2 6153.4 6154.4 6155.4 5918.6 5926.8 5945.6 6026.9 6096.1 6032.8 6060.2 6063.0 6086.1 6099.5 4842.6 4847.9 4853.3 4859.0 4891.2 4339.5 4343.9 4364.2 4408.7 4431.7 5488.5 5554.6 5560.4 5572.1 5598.0 6102.9 6115.5 6128.3 6135.0 6143.8 5623.6 5679.0 5684.1 5691.2 5702.8 5272.6 5327.3 5331.1 5333.3 5348.6 6327.7 6357.2 6364.1 6366.2 6376.2 6030.7 6100.5 6106.8 6134.4 6159.6 6250.8 6298.8 6330.9 6338.0 6364.4 6054.9 6056.8 6062.7 6068.7 6076.3 5716.8 5746.0 5771.7 5807.2 6156.8 5620.7 5629.1 5637.7 5638.3 5649.5 5625.0 5631.4 5637.0 5637.5 5648.2 5322.1 5532.0 5549.4 5549.6 5552.4 5551.6 5617.3 5621.6 5627.8 5634.7 5735.8 5764.3 5798.5 5843.7 5849.0 15:51:17 INFO - PROCESS | 1656 | 15:51:17 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:71000.77 Median:21734.83 stddev:153221.96 (705.0%) stddev-sans-first:153560.34 15:51:17 INFO - PROCESS | 1656 | Values: 493.9 522.6 522.6 526.7 566.1 30392.0 41741.4 42518.4 42704.0 45196.5 44071.8 44172.6 44302.3 44361.8 44592.6 9693.8 9887.3 9895.0 9905.6 9907.9 8695.7 8930.0 8935.9 9000.2 9007.2 11911.0 12209.9 12220.3 12349.4 12356.3 12202.6 12324.9 12325.2 12334.8 12343.7 8714.4 8942.6 11694.7 11902.0 11988.3 39886.8 42394.4 42415.6 42438.2 42486.6 3387.9 3541.8 3567.9 3937.3 3959.9 30456.7 31692.0 31876.2 31923.7 32177.8 3218.2 3293.8 3309.3 3335.2 3387.9 482853.0 487560.0 491777.0 492108.0 495136.0 59280.7 59597.7 59708.0 59765.7 59800.0 64823.0 64825.0 64881.0 64903.7 64949.7 16187.4 16426.4 16445.5 16451.3 16474.3 10534.4 12602.7 12606.8 12612.7 12623.0 15703.9 15976.1 17827.6 17829.8 18361.4 5594.3 6604.4 6632.7 6653.6 6678.3 905.2 925.8 933.5 970.9 1042.3 20800.6 29946.3 30030.7 30597.2 30825.0 1057.1 1066.8 1100.1 1113.6 1133.1 15384.3 15661.7 15688.5 15980.8 16193.5 546467.0 651348.0 653366.7 655323.0 656515.0 57534.8 57708.0 57743.8 57798.7 57828.1 1343.1 1390.1 1405.7 1407.1 1411.4 26525.7 29371.4 29633.7 30331.4 30672.4 51223.5 51545.7 51629.5 51690.2 51719.5 585245.0 587997.0 589474.0 589697.0 589977.0 33921.0 41394.8 41600.8 41731.0 42843.6 47862.0 47957.8 48097.0 48944.8 49010.8 31450.5 37581.0 39509.8 39702.8 39783.6 32506.8 39090.8 39365.6 39808.0 40446.2 36697.0 37363.8 37957.0 38088.8 38205.8 33826.3 34176.5 34788.8 34843.7 35136.9 25295.6 25369.7 25585.0 25701.2 25966.5 15:51:17 INFO - PROCESS | 1656 | 15:51:17 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:32872.86 Median:373.71 stddev:94778.08 (25361.4%) stddev-sans-first:95012.51 15:51:17 INFO - PROCESS | 1656 | Values: 355.2 372.7 374.8 382.0 401.2 15300.5 15453.2 17124.8 20506.0 21162.0 20186.7 20291.6 20773.8 20942.7 21118.7 5921.4 6055.7 6056.0 6215.1 6236.0 5969.3 6118.5 6165.1 6173.1 6176.7 4167.4 4405.5 6395.6 6705.6 6757.0 5784.2 6115.7 6804.6 6808.2 6829.3 5970.2 5975.6 5980.5 5982.9 5997.7 13549.6 25058.0 25311.5 25366.9 25467.4 2299.3 2349.2 2358.9 2414.6 2489.0 9644.0 9808.8 9895.0 10381.0 10419.3 1833.5 1875.6 1880.5 1891.7 1900.8 112106.0 117595.4 119268.4 121585.0 125143.4 9042.0 10579.7 10920.2 11119.2 11122.2 14118.2 14125.2 14141.0 14149.9 14177.9 7990.4 8252.6 8345.3 8378.1 8406.2 8190.1 8196.3 8205.3 8295.3 8366.7 4381.8 4382.1 4382.7 4393.6 4471.8 1517.9 1601.5 1641.7 1827.9 1891.9 788.7 845.8 873.6 878.2 885.6 20015.0 21051.2 21142.2 21229.7 21262.1 796.7 900.6 905.8 908.5 916.9 10736.4 11013.8 11050.8 11229.7 11253.6 561303.0 566875.2 572504.0 574043.0 578738.0 65635.7 67710.6 68061.0 122727.8 126964.0 941.3 956.2 1000.0 1003.1 1004.4 16589.2 18220.3 18514.4 18766.3 18780.8 50489.7 51724.0 52143.5 52736.0 53102.0 61043.8 73412.0 74066.6 74093.0 75224.0 7642.5 7678.3 7912.1 7983.8 7987.8 10671.4 12348.0 12467.2 12644.9 12648.2 10722.2 11014.0 11049.4 11187.7 11227.8 7122.8 10633.5 10824.1 10849.3 11017.3 7825.5 7839.6 7861.7 7934.7 8177.1 11803.8 11986.5 12286.2 12324.7 12333.9 11622.9 11648.9 11753.2 11880.8 12124.5 15:51:17 INFO - PROCESS | 1656 | 15:51:17 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5609.21 Median:5017.81 stddev:3145.33 (62.7%) stddev-sans-first:3139.21 15:51:17 INFO - PROCESS | 1656 | Values: 1529.3 1540.6 1748.6 1846.7 1905.3 8265.4 8733.1 8733.2 8736.5 8739.6 7746.5 7993.2 8007.1 8330.4 8528.9 2302.7 2342.5 2344.1 2346.0 2350.9 1945.5 2317.8 2336.2 2367.9 2369.5 2264.9 2268.3 2275.6 2277.7 2363.7 2325.2 2327.1 2331.9 2334.3 2336.1 2234.0 2326.9 2331.2 2333.5 2335.6 8664.0 8724.1 8729.9 8733.6 8736.0 5109.7 5281.1 5285.8 5286.4 5291.0 3430.0 3496.8 3619.8 3653.6 3662.7 1073.3 1080.8 1082.3 1102.3 1102.7 11338.0 11497.3 11549.0 11555.4 11556.4 4485.9 4520.7 4712.0 4718.2 4720.2 9726.7 9730.7 9736.0 9748.0 9785.6 5254.7 5278.2 5279.0 5281.7 5284.7 4315.7 4351.7 4521.5 4847.2 4855.0 3261.5 3726.0 3786.5 3873.9 3883.1 1243.7 1260.7 1263.6 1270.3 1272.8 711.7 760.8 789.2 791.4 902.9 5040.5 5522.4 5755.6 5768.3 5839.8 744.9 863.6 939.6 941.7 949.5 3719.2 3773.8 3777.3 3885.6 3992.9 10243.7 10569.3 10581.9 10600.0 10611.2 10091.4 10485.5 10566.9 10612.9 10619.9 7888.0 8008.6 8076.2 8096.0 8102.6 5730.7 5739.2 5759.9 5936.3 6083.6 9816.8 9913.8 9958.5 9962.2 9963.8 9616.5 9638.9 9893.4 10139.1 10152.4 6829.7 7083.7 7372.9 7533.8 7542.3 7855.0 8220.8 8241.3 8302.2 8304.2 7302.5 7403.7 7428.2 7502.7 7606.1 7224.6 7239.9 7241.9 7250.2 7349.6 6777.4 6806.5 6904.3 6917.6 6920.4 4759.7 4814.4 4840.9 4878.6 4981.7 4743.0 4850.9 4950.7 5002.9 5032.7 15:51:17 INFO - PROCESS | 1656 | 15:51:17 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4111.97 Median:4513.29 stddev:1266.32 (28.1%) stddev-sans-first:1236.52 15:51:17 INFO - PROCESS | 1656 | Values: 265.7 297.0 298.5 310.1 314.7 5000.9 5194.3 5242.7 5289.9 5339.7 4539.6 4704.4 5054.7 5093.2 5177.5 3634.7 3985.8 3989.6 4007.5 4019.9 3717.5 3849.9 4001.1 4031.5 4051.5 3645.8 4020.2 4024.0 4025.8 4047.9 3593.1 3952.5 3975.7 3978.0 3997.7 2566.4 2958.6 3112.7 3411.6 3491.0 4211.6 4648.8 4997.9 5040.9 5059.8 3796.7 4002.3 4481.9 4549.0 4586.5 4509.5 5232.9 5464.7 5487.0 5541.7 3287.0 3729.7 3739.2 3758.5 3822.6 5624.8 5655.6 5667.4 5698.9 5704.7 3670.0 3813.7 3822.4 3837.0 3838.7 3325.9 3723.3 3843.3 3858.3 3877.1 3793.7 4456.3 4471.7 4476.9 4517.1 4555.5 4832.3 5462.6 5497.4 5535.9 1131.9 1135.0 1238.3 1303.7 1315.7 707.2 726.1 780.5 798.4 811.5 4225.4 4260.7 4267.9 4269.0 4299.5 4197.5 4251.9 4625.6 4630.5 4667.8 3787.7 3913.0 4191.2 4238.9 4246.0 2175.6 2199.5 2281.9 2307.5 2444.6 4237.4 4632.7 4739.6 4743.6 4785.4 4423.9 4668.5 4711.5 4713.9 4740.1 3976.4 4646.9 4709.4 4721.3 4779.3 4657.8 4719.5 4720.4 4733.1 4742.6 3882.9 3905.0 4158.2 4610.7 4760.4 4277.1 4610.4 4749.8 4797.6 4804.8 2664.4 2889.1 2929.4 3121.5 3306.9 3999.5 4327.0 4559.5 4669.1 4685.9 4911.6 5150.0 5313.6 5355.3 5508.3 4807.3 5447.4 5469.7 5483.0 5506.2 4916.9 4938.8 5013.1 5290.4 5381.5 4896.5 5517.5 5533.3 5536.0 5556.8 4384.3 4551.0 5145.7 5425.6 5451.7 15:51:17 INFO - PROCESS | 1656 | 15:51:17 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:24466.97 Median:3736.30 stddev:38900.49 (1041.1%) stddev-sans-first:38968.76 15:51:17 INFO - PROCESS | 1656 | Values: 720.7 731.8 754.3 790.4 798.0 15711.8 15996.2 15999.4 16008.0 16019.9 14353.7 14483.3 14487.9 14504.2 14509.7 673.8 693.7 694.7 697.6 823.7 695.6 698.6 835.7 862.0 867.8 896.1 901.1 902.8 903.5 906.7 902.9 907.0 907.9 908.1 909.2 529.6 673.5 674.4 676.0 676.3 12449.1 14480.5 14990.0 15033.0 15357.8 14175.4 14656.9 14833.9 14866.9 14922.9 11835.2 11857.5 11956.7 12050.3 12205.7 3320.0 3885.1 3895.9 3922.8 3949.5 68416.7 88635.0 92464.0 98757.5 108841.0 11017.2 11092.6 11112.9 11177.5 11279.5 21264.3 21391.5 21437.1 21448.5 21471.0 8028.2 8066.0 8146.4 8191.8 8221.5 4312.7 4339.5 4522.1 4669.3 4676.2 3029.5 3439.5 3555.6 3580.8 3587.5 666.0 671.7 688.8 699.6 703.0 586.9 593.4 616.3 661.0 673.2 9122.9 9429.2 9536.7 9663.5 9782.7 622.5 654.5 664.2 684.5 696.5 4031.7 4248.5 4699.6 4741.3 4823.9 93266.5 129259.0 129666.4 154587.0 164773.3 137399.3 138550.0 139534.0 141826.0 142914.0 113509.0 118023.0 118286.4 119844.4 121755.4 7477.5 8511.0 9002.9 9083.7 9131.3 64394.0 64630.7 64858.7 66251.4 66538.0 78619.6 88122.6 88250.0 88721.6 89433.6 12524.7 12803.6 13136.3 13150.4 13605.3 14713.6 14715.5 14849.8 15020.8 15331.2 11989.7 13540.3 13599.3 14009.9 14072.9 13501.1 13622.4 13941.2 13944.7 14361.9 13469.7 13773.4 13988.5 14200.1 14201.6 10273.3 11510.0 11520.8 11653.9 12212.3 10255.6 10368.8 10369.6 11464.9 11668.8 15:51:17 INFO - PROCESS | 1656 | -------- Summary: end -------- 15:51:17 INFO - PROCESS | 1656 | 15:51:18 INFO - TEST-INFO | 1656: exit 0 15:51:18 INFO - TEST-OK | dromaeo_css | took 1118869ms 15:51:18 INFO - TEST-START | kraken 15:51:18 INFO - operating with platform_type : mac_ 15:51:18 INFO - Initialising browser for kraken test... 15:51:18 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/tmpoUjJbc/profile http://localhost:49271/getInfo.html) 15:51:20 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 15:51:20 INFO - PROCESS | 1666 | colorDepth:24 15:51:20 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 15:51:20 INFO - PROCESS | 1666 | __metrics 15:51:20 INFO - PROCESS | 1666 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:51:20 INFO - PROCESS | 1666 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:51:21 INFO - TEST-INFO | 1666: exit 0 15:51:21 INFO - Browser initialized. 15:51:21 INFO - Running cycle 1/1 for kraken test... 15:51:21 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/tmpoUjJbc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:51:33 INFO - PROCESS | 1667 | RSS: Main: 236556288 15:51:33 INFO - PROCESS | 1667 | 15:51:35 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 15:51:36 INFO - PROCESS | 1667 | RSS: Main: 295739392 15:51:36 INFO - PROCESS | 1667 | 15:51:39 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 15:51:40 INFO - PROCESS | 1667 | RSS: Main: 295288832 15:51:40 INFO - PROCESS | 1667 | 15:51:43 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 15:51:44 INFO - PROCESS | 1667 | RSS: Main: 386990080 15:51:44 INFO - PROCESS | 1667 | 15:51:46 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 15:51:46 INFO - PROCESS | 1667 | RSS: Main: 286998528 15:51:46 INFO - PROCESS | 1667 | 15:51:49 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:51:49 INFO - PROCESS | 1667 | RSS: Main: 289402880 15:51:49 INFO - PROCESS | 1667 | 15:51:52 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 15:51:52 INFO - PROCESS | 1667 | RSS: Main: 327241728 15:51:52 INFO - PROCESS | 1667 | 15:51:55 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 15:51:56 INFO - PROCESS | 1667 | RSS: Main: 327987200 15:51:56 INFO - PROCESS | 1667 | 15:51:59 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 15:51:59 INFO - PROCESS | 1667 | RSS: Main: 326914048 15:51:59 INFO - PROCESS | 1667 | 15:52:01 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:52:02 INFO - PROCESS | 1667 | RSS: Main: 302084096 15:52:02 INFO - PROCESS | 1667 | 15:52:03 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:52:04 INFO - PROCESS | 1667 | RSS: Main: 305926144 15:52:04 INFO - PROCESS | 1667 | 15:52:06 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:52:07 INFO - PROCESS | 1667 | RSS: Main: 303435776 15:52:07 INFO - PROCESS | 1667 | 15:52:10 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:52:11 INFO - PROCESS | 1667 | RSS: Main: 315195392 15:52:11 INFO - PROCESS | 1667 | 15:52:14 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:52:14 INFO - PROCESS | 1667 | RSS: Main: 317079552 15:52:14 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 15:52:16 INFO - PROCESS | 1667 | RSS: Main: 360476672 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | __start_tp_report 15:52:16 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 15:52:16 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 15:52:16 INFO - PROCESS | 1667 | |i|pagename|runs| 15:52:16 INFO - PROCESS | 1667 | |0;ai-astar;113;114;113;114;114;115;113;113;113;116 15:52:16 INFO - PROCESS | 1667 | |1;audio-beat-detection;152;133;102;179;143;143;109;114;134;106 15:52:16 INFO - PROCESS | 1667 | |2;audio-dft;182;183;183;182;184;181;183;181;180;181 15:52:16 INFO - PROCESS | 1667 | |3;audio-fft;76;60;77;115;90;72;58;59;89;57 15:52:16 INFO - PROCESS | 1667 | |4;audio-oscillator;83;71;92;70;68;71;122;98;100;89 15:52:16 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;89;85;91;86;88;84;88;84;88;84 15:52:16 INFO - PROCESS | 1667 | |6;imaging-darkroom;94;94;94;95;94;94;95;94;94;93 15:52:16 INFO - PROCESS | 1667 | |7;imaging-desaturate;86;87;86;85;101;131;140;83;86;85 15:52:16 INFO - PROCESS | 1667 | |8;json-parse-financial;57;54;55;54;56;56;55;56;55;56 15:52:16 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;63;74;75;73;71;68;70;70;69;70 15:52:16 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;87;86;84;85;77;87;76;98;97;105 15:52:16 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;140;146;143;145;146;149;157;161;178;145 15:52:16 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;170;170;170;170;169;171;222;186;169;169 15:52:16 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;69;70;68;70;67;69;69;67;68;68 15:52:16 INFO - PROCESS | 1667 | __end_tp_report 15:52:16 INFO - PROCESS | 1667 | __start_cc_report 15:52:16 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1958 15:52:16 INFO - PROCESS | 1667 | __end_cc_report 15:52:16 INFO - PROCESS | 1667 | __startTimestamp1475362336735__endTimestamp 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | ------- Summary: start ------- 15:52:16 INFO - PROCESS | 1667 | Number of tests: 14 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:1.03 (0.9%) stddev-sans-first:1.05 15:52:16 INFO - PROCESS | 1667 | Values: 113.0 114.0 113.0 114.0 114.0 115.0 113.0 113.0 113.0 116.0 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:131.50 Median:138.50 stddev:24.23 (17.5%) stddev-sans-first:24.53 15:52:16 INFO - PROCESS | 1667 | Values: 152.0 133.0 102.0 179.0 143.0 143.0 109.0 114.0 134.0 106.0 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:182.00 Median:182.50 stddev:1.25 (0.7%) stddev-sans-first:1.32 15:52:16 INFO - PROCESS | 1667 | Values: 182.0 183.0 183.0 182.0 184.0 181.0 183.0 181.0 180.0 181.0 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:75.30 Median:74.00 stddev:18.64 (25.2%) stddev-sans-first:19.77 15:52:16 INFO - PROCESS | 1667 | Values: 76.0 60.0 77.0 115.0 90.0 72.0 58.0 59.0 89.0 57.0 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:86.40 Median:77.00 stddev:17.38 (22.6%) stddev-sans-first:18.39 15:52:16 INFO - PROCESS | 1667 | Values: 83.0 71.0 92.0 70.0 68.0 71.0 122.0 98.0 100.0 89.0 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:88.00 stddev:2.45 (2.8%) stddev-sans-first:2.46 15:52:16 INFO - PROCESS | 1667 | Values: 89.0 85.0 91.0 86.0 88.0 84.0 88.0 84.0 88.0 84.0 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 15:52:16 INFO - PROCESS | 1667 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 95.0 94.0 94.0 93.0 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:97.00 Median:85.50 stddev:21.00 (24.6%) stddev-sans-first:21.89 15:52:16 INFO - PROCESS | 1667 | Values: 86.0 87.0 86.0 85.0 101.0 131.0 140.0 83.0 86.0 85.0 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:56.00 stddev:0.97 (1.7%) stddev-sans-first:0.83 15:52:16 INFO - PROCESS | 1667 | Values: 57.0 54.0 55.0 54.0 56.0 56.0 55.0 56.0 55.0 56.0 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:70.50 stddev:3.40 (4.8%) stddev-sans-first:2.37 15:52:16 INFO - PROCESS | 1667 | Values: 63.0 74.0 75.0 73.0 71.0 68.0 70.0 70.0 69.0 70.0 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:86.50 stddev:9.22 (10.7%) stddev-sans-first:9.77 15:52:16 INFO - PROCESS | 1667 | Values: 87.0 86.0 84.0 85.0 77.0 87.0 76.0 98.0 97.0 105.0 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:151.00 Median:147.50 stddev:11.43 (7.7%) stddev-sans-first:11.41 15:52:16 INFO - PROCESS | 1667 | Values: 140.0 146.0 143.0 145.0 146.0 149.0 157.0 161.0 178.0 145.0 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.60 Median:170.00 stddev:16.76 (9.9%) stddev-sans-first:17.61 15:52:16 INFO - PROCESS | 1667 | Values: 170.0 170.0 170.0 170.0 169.0 171.0 222.0 186.0 169.0 169.0 15:52:16 INFO - PROCESS | 1667 | 15:52:16 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:1.08 (1.6%) stddev-sans-first:1.13 15:52:16 INFO - PROCESS | 1667 | Values: 69.0 70.0 68.0 70.0 67.0 69.0 69.0 67.0 68.0 68.0 15:52:16 INFO - PROCESS | 1667 | -------- Summary: end -------- 15:52:16 INFO - PROCESS | 1667 | 15:52:17 INFO - TEST-INFO | 1667: exit 0 15:52:17 INFO - TEST-OK | kraken | took 59450ms 15:52:17 INFO - SUITE-END | took 1178s 15:52:17 INFO - Completed test suite (00:19:38) 15:52:17 INFO - javascript benchmark 15:52:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3272.051105179498, 3291.1278511194537, 3304.702576768092, 3325.9523250176208, 3347.698707116359, 4797.96843678609, 5488.204210389491, 5591.720413979305, 5611.607187496877, 5628.971855140729, 4990.401151861782, 5537.750801213945, 5550.583706222029, 5658.43415658434, 5663.7168141592965, 5049.368828896388, 5056.696598204101, 5093.847184584469, 5115.820946266881, 5135.717535535544, 5141.357330333708, 5176.5082317179995, 5199.272101905736, 5204.739763011853, 5224.164133738603, 5058.924116138261, 5118.6416950813455, 5161.019406312788, 5186.585073194155, 5224.764885580158, 5105.33630628016, 5126.641135120543, 5126.974365128188, 5130.0, 5130.230465430178, 5066.138756411419, 5104.464031276737, 5111.948880511184, 5120.001599688062, 5123.974380128113, 5576.9163462548095, 5577.860553486195, 5589.385167631557, 5597.076482380402, 5611.382747897728, 5039.244113382985, 6029.487493563009, 6056.636601803906, 6091.375591859004, 6133.457080270857, 6106.339326077114, 6158.8152355428965, 6178.752849885999, 6179.165812615284, 6220.066989951498, 5669.744861481243, 5694.288213973254, 5694.515966142924, 5701.800436984686, 5705.62913410627, 6115.757578773014, 6144.402455361638, 6191.81424557264, 6195.380461953769, 6270.372962703693, 5961.5230781537375, 5962.22491076166, 5966.194563733927, 5972.611780234271, 5983.311919129269, 6082.939170608237, 6150.169727086786, 6153.4154255345675, 6154.446099851035, 6155.353687862799, 5918.556108291896, 5926.7925622603, 5945.554083443672, 6026.879462410728, 6096.0855871619615, 6032.819015429544, 6060.151578778975, 6062.969685151546, 6086.1175129606845, 6099.451049405575, 4842.564169224786, 4847.927281090787, 4853.320535125014, 4858.951410485921, 4891.217405215149, 4339.544347843431, 4343.9348409773875, 4364.214441400578, 4408.713433626804, 4431.667624928144, 5488.506034456918, 5554.555635549146, 5560.443955604488, 5572.108462646038, 5598.048331783607, 6102.852830488404, 6115.541334399938, 6128.295246046672, 6134.9693251534345, 6143.784967526204, 5623.578231632644, 5679.0, 5684.090545512699, 5691.203231547586, 5702.800401985911, 5272.623110070475, 5327.280817089643, 5331.120365139786, 5333.333333333333, 5348.625596208306, 6327.683615819191, 6357.237131544244, 6364.1408409865, 6366.204224471941, 6376.234851817719, 6030.698465076816, 6100.511959043355, 6106.755729770759, 6134.355892631388, 6159.630422174684, 6250.781222657185, 6298.779542715983, 6330.9050364245495, 6338.0, 6364.395382438846, 6054.939450605438, 6056.818295451144, 6062.7271772769445, 6068.666223357758, 6076.270847498328, 5716.79991200306, 5746.0, 5771.740271687868, 5807.186993820953, 6156.78451254204, 5620.74706638194, 5629.127485239795, 5637.661740295432, 5638.323401191883, 5649.548036157015, 5625.0, 5631.380548139619, 5636.985342638364, 5637.49262566371, 5648.2092507049865, 5322.138639165901, 5532.031894418541, 5549.417311182429, 5549.5837812165055, 5552.416996215501, 5551.583631227755, 5617.297837770278, 5621.550275977994, 5627.774889004394, 5634.661920284796, 5735.7705691773535, 5764.308283006066, 5798.507126894093, 5843.678597677169, 5849.0], "value": 5547.007571973747, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.8814684475727, 522.581934452438, 522.6158773301623, 526.7261024267381, 566.1309889319895, 30392.0, 41741.37387939183, 42518.362224566656, 42704.0, 45196.54803451965, 44071.77964110175, 44172.55827441725, 44302.33546496797, 44361.778191109, 44592.55407445924, 9693.806123877535, 9887.258455615822, 9894.950525247365, 9905.603775848957, 9907.900920990805, 8695.69565065223, 8930.0, 8935.910640893573, 9000.189982901571, 9007.234385077243, 11911.04711623068, 12209.938950305235, 12220.327881966488, 12349.382530873465, 12356.258624482514, 12202.572909948154, 12324.938375308066, 12325.198862073936, 12334.81497777534, 12343.69140771488, 8714.433561818461, 8942.59758310871, 11694.707632309175, 11902.0, 11988.340641264727, 39886.80056599728, 42394.364084538756, 42415.57584424178, 42438.1512369754, 42486.57513424857, 3387.8644854205804, 3541.769784963969, 3567.8572857085687, 3937.310970580137, 3959.8614048508166, 30456.69543304583, 31692.049238522883, 31876.20309492258, 31923.680763192304, 32177.839110804296, 3218.1954511372155, 3293.75296852737, 3309.3381323735107, 3335.2328964338094, 3387.8644854205804, 482852.9999999965, 487560.0, 491777.0, 492108.0, 495136.0, 59280.70359648261, 59597.70201149053, 59708.0, 59765.70117149386, 59800.0, 64823.02765458522, 64825.0, 64881.0, 64903.675481623235, 64949.67525162344, 16187.352505899868, 16426.42507512231, 16445.506634800975, 16451.341946322013, 16474.341026359052, 10534.36793792375, 12602.747945041048, 12606.81089783654, 12612.747745045048, 12622.990160787114, 15703.900726949232, 15976.121313327878, 17827.554311142325, 17829.821701783076, 18361.3573524926, 5594.300712410949, 6604.447954730635, 6632.668366581652, 6653.567518111307, 6678.265390807007, 905.248643625789, 925.847235206197, 933.4772527384687, 970.8980307351067, 1042.2547878266923, 20800.583988320148, 29946.25134371658, 30030.69969300279, 30597.235069123006, 30825.0, 1057.1225882517492, 1066.7866426714688, 1100.0539535999035, 1113.6046703420332, 1133.1274918312854, 15384.30770615325, 15661.686766264611, 15688.450904218298, 15980.76028859568, 16193.514194574182, 546467.0, 651348.0, 653366.7331663311, 655323.0, 656515.0, 57534.8493030137, 57708.0, 57743.8451230973, 57798.7110064447, 57828.13257801136, 1343.1135450602555, 1390.082545519952, 1405.6977749783846, 1407.0643022389995, 1411.386047069528, 26525.734742652327, 29371.41257174887, 29633.703662963093, 30331.39337213199, 30672.35891023707, 51223.48776512187, 51545.71135721487, 51629.48370516247, 51690.22464663108, 51719.482805171465, 585245.0, 587997.0, 589474.0, 589697.0, 589977.0, 33921.0, 41394.793026035884, 41600.79199603983, 41731.0, 42843.57156428521, 47862.0, 47957.801054973905, 48097.03805923932, 48944.77638058935, 49010.75494622504, 31450.528242076845, 37581.0, 39509.80245098871, 39702.801485992386, 39783.60216397915, 32506.83746581252, 39090.80454597822, 39365.62220322275, 39808.0, 40446.1910761789, 36697.0, 37363.81318093501, 37957.0, 38088.809555952044, 38205.80897095497, 33826.27910929845, 34176.48735269023, 34788.82605586956, 34843.65156348404, 35136.94589162329, 25295.620565691162, 25369.74630253674, 25585.0, 25701.228963131136, 25966.48067038611], "value": 20536.148921683696, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.1883945185249, 372.6515707813195, 374.76764406068236, 381.95416550014005, 401.22563452536593, 15300.54098377049, 15453.15007674579, 17124.82875171248, 20506.0, 21162.047707853144, 20186.697199542017, 20291.59416811663, 20773.792262077375, 20942.685859712117, 21118.683219751714, 5921.407859214066, 6055.666938318391, 6056.000759874615, 6215.067739839038, 6236.0, 5969.253843269591, 6118.541109416789, 6165.0752387142065, 6173.074038894157, 6176.691165441733, 4167.354060120671, 4405.493368262659, 6395.648239346834, 6705.564138331018, 6757.0, 5784.161296611967, 6115.694215289218, 6804.55770374924, 6808.21705503866, 6829.3170682931805, 5970.194023806817, 5975.551833612496, 5980.521558275327, 5982.8504287392725, 5997.700114994276, 13549.59351219465, 25057.997680092776, 25311.493770124493, 25366.858491367933, 25467.363315917064, 2299.2872209615075, 2349.2247558305717, 2358.917437889683, 2414.5653782319177, 2489.0417189382038, 9643.951780241054, 9808.754781130458, 9895.0, 10381.013803688638, 10419.270651054429, 1833.5232839461837, 1875.5967466994662, 1880.482867211514, 1891.6878715012006, 1900.828925396721, 112106.0, 117595.41202294019, 119268.40365798202, 121585.0, 125143.37428312891, 9041.954790226007, 10579.735506612396, 10920.235583509078, 11119.221654484109, 11122.165837562216, 14118.223497707519, 14125.223112728692, 14141.010129291057, 14149.938754593446, 14177.936654750934, 7990.4007199460275, 8252.628631711588, 8345.332373410112, 8378.078411374743, 8406.201410865959, 8190.058143313476, 8196.303314218358, 8205.261526462647, 8295.336373090138, 8366.665333386718, 4381.78091095444, 4382.05785756064, 4382.69321147523, 4393.648508119343, 4471.8434854779925, 1517.855803698647, 1601.4635097242553, 1641.7455294429383, 1827.8575890068707, 1891.8770279932035, 788.7441916119892, 845.7716416567492, 873.5675840459013, 878.1920633017639, 885.6103314541581, 20015.0, 21051.157953681683, 21142.154313827275, 21229.68155477669, 21262.149514019577, 796.6853093028221, 900.5812297281665, 905.805251870851, 908.4821651658623, 916.9083091690777, 10736.409497477558, 11013.83479247812, 11050.834237486444, 11229.66311010671, 11253.606123785628, 561303.0, 566875.1593694201, 572504.0, 574043.0, 578738.0, 65635.67182164155, 67710.61446927732, 68061.0, 122727.77272227342, 126964.0, 941.317544780029, 956.1872408452759, 999.9550020249106, 1003.0821798054699, 1004.42747633848, 16589.17054147312, 18220.271189152816, 18514.351997680016, 18766.343177988703, 18780.812191877907, 50489.74755126201, 51724.0, 52143.47856521386, 52736.0, 53102.0, 61043.81127604203, 73412.0, 74066.62966685132, 74093.0, 75224.0, 7642.5032372895585, 7678.308952194329, 7912.0901096374655, 7983.84032319362, 7987.760367188994, 10671.413072280788, 12348.0, 12467.189632673848, 12644.936775316064, 12648.241105533698, 10722.249442538947, 11014.0, 11049.447527623426, 11187.72030699239, 11227.800076978023, 7122.7507037253445, 10633.468326583794, 10824.134069274282, 10849.294795838245, 11017.338959662446, 7825.5131525010065, 7839.568823714522, 7861.731719147568, 7934.682612695427, 8177.100518942792, 11803.763924721628, 11986.520539178684, 12286.201396909173, 12324.691882703004, 12333.93833030829, 11622.94188529052, 11648.88351116478, 11753.236039657395, 11880.821787673365, 12124.454399552218], "value": 9315.164616618145, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1529.2659523428758, 1540.5686407805815, 1748.6415284866603, 1846.6768315544775, 1905.333133403309, 8265.380096492765, 8733.083026282236, 8733.214010739031, 8736.519491427976, 8739.606717697703, 7746.496477728943, 7993.1607181246, 8007.079185893624, 8330.375221858354, 8528.872066919, 2302.689136966513, 2342.4846533762543, 2344.0975224538506, 2345.95308093838, 2350.8942097605564, 1945.5136215946013, 2317.756635553268, 2336.158982766203, 2367.8934447949796, 2369.4668699542553, 2264.864108153516, 2268.330842401487, 2275.590393729128, 2277.738060123082, 2363.6927199464044, 2325.1513197682807, 2327.1156960355042, 2331.941701457477, 2334.334714606329, 2336.0655737704883, 2234.0, 2326.8836558172025, 2331.1724337860155, 2333.5099629077913, 2335.6029474989123, 8663.956680216621, 8724.083971182998, 8729.912700872974, 8733.563321833946, 8736.0, 5109.718965456898, 5281.102212623825, 5285.814996475144, 5286.365636123652, 5290.9735451322495, 3429.9828500857334, 3496.807675577842, 3619.7647152934974, 3653.6346365363515, 3662.7252956028406, 1073.2755390111722, 1080.827067669177, 1082.3181395720724, 1102.2890235797865, 1102.6581759654532, 11337.979581837591, 11497.310161390344, 11549.0, 11555.422228888521, 11556.422178891022, 4485.932711009337, 4520.660950428731, 4711.952880471152, 4718.245080787057, 4720.197566413718, 9726.659566915125, 9730.659426920165, 9736.026397360207, 9747.951260243653, 9785.608575656894, 5254.65844720092, 5278.20826875964, 5279.0, 5281.709505977208, 5284.682919024871, 4315.719478233905, 4351.69538132334, 4521.525239849769, 4847.224444088929, 4855.004724031635, 3261.510773383964, 3725.9813700931863, 3786.450964610171, 3873.86441474547, 3883.0680636647003, 1243.738814848892, 1260.6785269756338, 1263.564070395712, 1270.30133426615, 1272.7581759465672, 711.7224282529818, 760.8326168243088, 789.2344425906862, 791.3618809065294, 902.8916530016309, 5040.49595040493, 5522.447755224526, 5755.59710820247, 5768.2501274834, 5839.795607153729, 744.8547533231076, 863.6329559937002, 939.6100618243353, 941.7316064921464, 949.5442187749919, 3719.2189640175325, 3773.8301776420126, 3777.338965680995, 3885.611438856148, 3992.9201415972097, 10243.743906402251, 10569.260151789455, 10581.89418105809, 10600.04599586041, 10611.204159688054, 10091.37624217694, 10485.475726213659, 10566.947165264124, 10612.946935265429, 10619.893801062046, 7887.9605601971625, 8008.639611217509, 8076.232757888109, 8096.02847658273, 8102.554359510165, 5730.713464326684, 5739.196512488172, 5759.94240057594, 5936.257967754031, 6083.6349819011, 9816.80366392654, 9913.801723965624, 9958.452285124387, 9962.203023758228, 9963.750906227402, 9616.471094089613, 9638.855417168608, 9893.406395616286, 10139.087482126644, 10152.39085654863, 6829.726810927706, 7083.716651333889, 7372.889406659012, 7533.811654708456, 7542.321191092828, 7855.0, 8220.794480138044, 8241.340692744687, 8302.169783021649, 8304.169583041648, 7302.525335853153, 7403.7408690695565, 7428.220036896048, 7502.699892004259, 7606.125295590857, 7224.602646854473, 7239.8914016290855, 7241.927580724336, 7250.238724934017, 7349.55902645843, 6777.390034896883, 6806.523543351917, 6904.275051119761, 6917.550359226634, 6920.377166055079, 4759.738214398241, 4814.422269327703, 4840.903181936271, 4878.609711223165, 4981.676191047571, 4743.0, 4850.878728031827, 4950.6782059166035, 5002.924956125588, 5032.6980381177245], "value": 4501.512011043924, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.697105299958, 296.98515074246285, 298.5491907220098, 310.08679439051986, 314.68845842615815, 5000.874978125548, 5194.27280180775, 5242.737863106844, 5289.947100528999, 5339.6893925766035, 4539.636829053676, 4704.364910737049, 5054.671446355985, 5093.173288824701, 5177.534021938025, 3634.67287944085, 3985.8405663773415, 3989.601039896016, 4007.537248904144, 4019.919601607966, 3717.4609681596153, 3849.940281949158, 4001.1197536541913, 4031.5363733170766, 4051.493563304587, 3645.76302540334, 4020.1758639478985, 4023.9537720192816, 4025.75845449274, 4047.8785636430953, 3593.13764696471, 3952.4664170336923, 3975.6819454443867, 3977.960220397788, 3997.660198883084, 2566.371239046427, 2958.6390260480207, 3112.7354174895163, 3411.6076651184935, 3490.9701638016845, 4211.599898009684, 4648.83729069481, 4997.925031124536, 5040.924386134211, 5059.848204553869, 3796.7152463565067, 4002.2995975704134, 4481.887952801173, 4549.0, 4586.54134586542, 4509.526499717515, 5232.947670523284, 5464.726763661841, 5486.972565137189, 5541.6674999499755, 3286.997465772938, 3729.664330210294, 3739.1960728443514, 3758.5113935188524, 3822.617738226183, 5624.762552238501, 5655.575831812589, 5667.376588575212, 5698.886022279531, 5704.686242256675, 3669.9816500917323, 3813.7139714520968, 3822.369309063973, 3836.9616303836606, 3838.654521093059, 3325.935700575841, 3723.2739615774663, 3843.2889915365186, 3858.344081506151, 3877.0695033191832, 3793.6775374093513, 4456.309272062771, 4471.6869819113, 4476.865694029184, 4517.051419201939, 4555.498895121534, 4832.347633069561, 5462.562994960394, 5497.39528651848, 5535.916961245584, 1131.8585176852894, 1134.9659510214706, 1238.3374894431436, 1303.7158398977, 1315.684235783405, 707.2326525719652, 726.1286456252518, 780.5433821214654, 798.3852433626075, 811.472542847154, 4225.408442818008, 4260.70048635163, 4267.935980960288, 4268.978655106766, 4299.54854740254, 4197.496300443966, 4251.893702657459, 4625.583697467244, 4630.513796051433, 4667.802555249665, 3787.7273635818838, 3912.960870391317, 4191.203671302443, 4238.901743636858, 4245.957540424556, 2175.5866385386726, 2199.483121466454, 2281.897314620846, 2307.4692820651344, 2444.633305004228, 4237.436351007657, 4632.745199014019, 4739.625508602536, 4743.620510359163, 4785.420811132388, 4423.911521769546, 4668.50980647034, 4711.481737008926, 4713.9057218856115, 4740.146773580788, 3976.3632283641723, 4646.886585987464, 4709.3642358281195, 4721.315409265637, 4779.330893674888, 4657.790399432101, 4719.504452032487, 4720.386349774507, 4733.083101343961, 4742.620590352694, 3882.8835134946, 3905.0042239228815, 4158.189153115113, 4610.677252592286, 4760.404949381327, 4277.139444341721, 4610.354550363019, 4749.786259618257, 4797.56821886032, 4804.750764801247, 2664.427148163154, 2889.147701428126, 2929.414117176616, 3121.4849549824025, 3306.933861322712, 3999.460072890239, 4326.9615292330245, 4559.511973340464, 4669.112868554964, 4685.882852928704, 4911.58251548608, 5150.0, 5313.582320044447, 5355.277037599874, 5508.3114610673665, 4807.302941073455, 5447.3735520414075, 5469.718169098509, 5482.972585137049, 5506.174073888948, 4916.934887347251, 4938.827141050046, 5013.092406404204, 5290.391604965478, 5381.515663590296, 4896.510348965075, 5517.531009864207, 5533.280673512418, 5535.979367530742, 5556.777728890799, 4384.276594361894, 4550.953280745381, 5145.665531740425, 5425.620206585501, 5451.672899626035], "value": 3710.2215191316927, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.6937051753004, 731.8426538294266, 754.3248792330864, 790.3519114326252, 797.9561124138178, 15711.842881571181, 15996.200189990512, 15999.360025598962, 16008.0, 16019.919900400497, 14353.712925741478, 14483.2758362082, 14487.92756036221, 14504.202268875208, 14509.709805803877, 673.8147009572382, 693.7259782385954, 694.7325279767301, 697.6372286411063, 823.7117009046831, 695.6174104242647, 698.584342316321, 835.7409203147043, 861.9870701939476, 867.7830542364409, 896.1218006353777, 901.0718959471722, 902.8374892519344, 903.5482258870564, 906.7461110888962, 902.9051949545302, 907.0476000199764, 907.9273658107336, 908.1463424381061, 909.2226146644656, 529.5684017525742, 673.5016088094775, 674.3896773420063, 675.9864802703919, 676.3371895542347, 12449.136698559292, 14480.493182738554, 14990.0, 15033.0, 15357.769633455508, 14175.362108705132, 14656.926715366355, 14833.851661483355, 14866.851331486654, 14922.850771492363, 11835.171537992344, 11857.525698972031, 11956.70108247292, 12050.337231452266, 12205.694857628541, 3320.0, 3885.1452680410266, 3895.941560876589, 3922.784246866421, 3949.5063117110362, 68416.6579167106, 88635.0, 92464.0, 98757.50621246919, 108841.0, 11017.228793984345, 11092.611758588571, 11112.944435277772, 11177.49701263445, 11279.492422840993, 21264.25575104864, 21391.46521336948, 21437.142514299252, 21448.463788405414, 21471.03380347888, 8028.197180281925, 8066.0, 8146.389020823462, 8191.795205119801, 8221.465604735673, 4312.663074446931, 4339.500957389878, 4522.072975040174, 4669.299605059268, 4676.158291507493, 3029.4546981543094, 3439.518467414564, 3555.6266592007983, 3580.785152890835, 3587.461880717913, 665.9567128136753, 671.7111641993989, 688.786476192372, 699.5592776550741, 702.9683664235122, 586.9413058694097, 593.4273426143706, 616.3251239892311, 660.9556900097834, 673.2022553274386, 9122.86315705265, 9429.245660347156, 9536.713898583053, 9663.46850923206, 9782.706518804447, 622.4677900395187, 654.4698793976894, 664.2162248546762, 684.5345165287553, 696.5228818259509, 4031.7177797554477, 4248.4901811782165, 4699.647526435531, 4741.336212930192, 4823.903521929542, 93266.53366733258, 129259.0, 129666.35166824295, 154587.04358303902, 164773.28400739984, 137399.31300343433, 138550.0, 139534.0, 141826.0, 142914.0, 113509.0, 118023.0, 118286.40856795834, 119844.40077799556, 121755.39122304332, 7477.513961592479, 8510.957445212734, 9002.864957025518, 9083.727488175366, 9131.315151363542, 64394.03408948963, 64630.67684661547, 64858.675706621165, 66251.4399126323, 66538.00192997206, 78619.60690196512, 88122.55938720265, 88250.0, 88721.55639221762, 89433.55283223542, 12524.686882828002, 12803.61589152327, 13136.343182840628, 13150.408231629408, 13605.319734013061, 14713.632159196106, 14715.488568603329, 14849.777253340992, 15020.849791502382, 15331.156786376856, 11989.700257493283, 13540.32298385057, 13599.274705348906, 14009.929950350182, 14072.859271407564, 13501.077292652044, 13622.38699258516, 13941.163530188222, 13944.651383715489, 14361.856381436051, 13469.720376564212, 13773.380197890918, 13988.454290756437, 14200.076994995292, 14201.644958876112, 10273.280870339004, 11510.0, 11520.769584608428, 11653.941730291293, 12212.267263964191, 10255.58977640886, 10368.792624147625, 10369.63706270277, 11464.87941526241, 11668.766624667629], "value": 7776.809415125636, "unit": "score"}], "name": "dromaeo_css", "value": 7187.262049981936, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 114.0, 114.0, 115.0, 113.0, 113.0, 113.0, 116.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 133.0, 102.0, 179.0, 143.0, 143.0, 109.0, 114.0, 134.0, 106.0], "value": 131.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 183.0, 183.0, 182.0, 184.0, 181.0, 183.0, 181.0, 180.0, 181.0], "value": 182.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 60.0, 77.0, 115.0, 90.0, 72.0, 58.0, 59.0, 89.0, 57.0], "value": 75.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 92.0, 70.0, 68.0, 71.0, 122.0, 98.0, 100.0, 89.0], "value": 86.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 91.0, 86.0, 88.0, 84.0, 88.0, 84.0, 88.0, 84.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0, 93.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 85.0, 101.0, 131.0, 140.0, 83.0, 86.0, 85.0], "value": 97.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 55.0, 54.0, 56.0, 56.0, 55.0, 56.0, 55.0, 56.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 74.0, 75.0, 73.0, 71.0, 68.0, 70.0, 70.0, 69.0, 70.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 84.0, 85.0, 77.0, 87.0, 76.0, 98.0, 97.0, 105.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 146.0, 143.0, 145.0, 146.0, 149.0, 157.0, 161.0, 178.0, 145.0], "value": 151.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 170.0, 170.0, 169.0, 171.0, 222.0, 186.0, 169.0, 169.0], "value": 176.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 68.0, 70.0, 67.0, 69.0, 69.0, 67.0, 68.0, 68.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1476.8, "alertThreshold": 2.0}]} 15:52:17 INFO - Return code: 0 15:52:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:52:18 INFO - # TBPL SUCCESS # 15:52:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:52:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:52:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:52:18 INFO - 2016-10-01 15:52:18.057 screenresolution[1671:10144] starting screenresolution argv=screenresolution get 15:52:18 INFO - 2016-10-01 15:52:18.059 screenresolution[1671:10144] Display 0: 1600x1200x32@60 15:52:18 INFO - 2016-10-01 15:52:18.066 screenresolution[1672:10147] starting screenresolution argv=screenresolution list 15:52:18 INFO - Available Modes on Display 0 15:52:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:52:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:52:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:52:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:52:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:52:18 INFO - Intel Iris: 15:52:18 INFO - Chipset Model: Intel Iris 15:52:18 INFO - Type: GPU 15:52:18 INFO - Bus: Built-In 15:52:18 INFO - VRAM (Dynamic, Max): 1536 MB 15:52:18 INFO - Vendor: Intel (0x8086) 15:52:18 INFO - Device ID: 0x0a2e 15:52:18 INFO - Revision ID: 0x0009 15:52:18 INFO - Displays: 15:52:18 INFO - Display: 15:52:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:52:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:52:18 INFO - Display Serial Number: 1600x1200 60 15:52:18 INFO - Main Display: Yes 15:52:18 INFO - Mirror: Off 15:52:18 INFO - Online: Yes 15:52:18 INFO - Rotation: Supported 15:52:18 INFO - Return code: 0 15:52:18 INFO - Running post-action listener: _resource_record_post_action 15:52:18 INFO - [mozharness: 2016-10-01 22:52:18.301923Z] Finished run-tests step (success) 15:52:18 INFO - Running post-run listener: _resource_record_post_run 15:52:18 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37401600; Write bytes: 709130752; Read time: 241; Write time: 2034 15:52:18 INFO - TinderboxPrint: CPU usage
53.7% 15:52:18 INFO - TinderboxPrint: I/O read bytes / time
37,401,600 / 241 15:52:18 INFO - TinderboxPrint: I/O write bytes / time
709,130,752 / 2,034 15:52:18 INFO - TinderboxPrint: CPU idle
2,244.8 (46.2%) 15:52:18 INFO - TinderboxPrint: CPU system
971.1 (20.0%) 15:52:18 INFO - TinderboxPrint: CPU user
1,637.7 (33.7%) 15:52:18 INFO - TinderboxPrint: Swap in / out
444,997,632 / 0 15:52:18 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 398070272; Write bytes: 393246720; Read time: 29284; Write time: 800 15:52:18 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 35419136; Write bytes: 297005568; Read time: 221; Write time: 1187 15:52:18 INFO - Running post-run listener: _upload_blobber_files 15:52:18 INFO - Blob upload gear active. 15:52:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:52:18 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 15:52:18 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'] 15:52:18 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 15:52:19 INFO - (blobuploader) - INFO - Open directory for files ... 15:52:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:52:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:52:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:52:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:52:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:52:19 INFO - (blobuploader) - INFO - Done attempting. 15:52:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:52:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:52:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:52:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:52:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:52:20 INFO - (blobuploader) - INFO - Done attempting. 15:52:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:52:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:52:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:52:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:52:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:52:22 INFO - (blobuploader) - INFO - Done attempting. 15:52:22 INFO - (blobuploader) - INFO - Iteration through files over. 15:52:22 INFO - Return code: 0 15:52:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:52:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:52:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d778b459abf7ba0ce74c638935b04706fdce7ceeb3ba201d6ae415b1738c001fba2ee18717a033f51b72c2c9017a4d8ad1ec5fa759fdceec36b3057e266776e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/165486d6a337b7ece13875e9ed8a39c03a9049ed4b783087afa98e7f7da0fcc5d3f8d761ea12d5792a8b6c1d649c22dd3988b94abad55588fe90d675787e06ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d16df1012573756b9ff56ec73a71b0d24a3242fe6aaefd6b1d120435557e3db3d625d923a04b9ee5f83b3d86da8ae6d3777fbb1b0e69446b6e234457600228b5"} 15:52:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:52:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:52:22 INFO - Contents: 15:52:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d778b459abf7ba0ce74c638935b04706fdce7ceeb3ba201d6ae415b1738c001fba2ee18717a033f51b72c2c9017a4d8ad1ec5fa759fdceec36b3057e266776e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/165486d6a337b7ece13875e9ed8a39c03a9049ed4b783087afa98e7f7da0fcc5d3f8d761ea12d5792a8b6c1d649c22dd3988b94abad55588fe90d675787e06ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d16df1012573756b9ff56ec73a71b0d24a3242fe6aaefd6b1d120435557e3db3d625d923a04b9ee5f83b3d86da8ae6d3777fbb1b0e69446b6e234457600228b5"} 15:52:22 INFO - Running post-run listener: copy_logs_to_upload_dir 15:52:22 INFO - Copying logs to upload dir... 15:52:22 INFO - mkdir: /builds/slave/test/build/upload/logs 15:52:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.659859 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-01 15:52:23.050969) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-01 15:52:23.054320) ========= 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.By3fmIZHpj/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.DWVdRKrzpQ/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d778b459abf7ba0ce74c638935b04706fdce7ceeb3ba201d6ae415b1738c001fba2ee18717a033f51b72c2c9017a4d8ad1ec5fa759fdceec36b3057e266776e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/165486d6a337b7ece13875e9ed8a39c03a9049ed4b783087afa98e7f7da0fcc5d3f8d761ea12d5792a8b6c1d649c22dd3988b94abad55588fe90d675787e06ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d16df1012573756b9ff56ec73a71b0d24a3242fe6aaefd6b1d120435557e3db3d625d923a04b9ee5f83b3d86da8ae6d3777fbb1b0e69446b6e234457600228b5"} build_url:https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011367 build_url: 'https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d778b459abf7ba0ce74c638935b04706fdce7ceeb3ba201d6ae415b1738c001fba2ee18717a033f51b72c2c9017a4d8ad1ec5fa759fdceec36b3057e266776e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/165486d6a337b7ece13875e9ed8a39c03a9049ed4b783087afa98e7f7da0fcc5d3f8d761ea12d5792a8b6c1d649c22dd3988b94abad55588fe90d675787e06ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d16df1012573756b9ff56ec73a71b0d24a3242fe6aaefd6b1d120435557e3db3d625d923a04b9ee5f83b3d86da8ae6d3777fbb1b0e69446b6e234457600228b5"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-01 15:52:23.090701) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:52:23.091141) ========= 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.By3fmIZHpj/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.DWVdRKrzpQ/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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.005092 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:52:23.139871) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-01 15:52:23.143274) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-01 15:52:23.143670) ========= ========= Total master_lag: 0.19 =========