builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0029 starttime: 1475361067.25 results: success (0) buildid: 20161001140155 builduid: f80e5b28e5fa4ab6b86efd293eb80da9 revision: 7c576fe3279d87543f0a03b844eba7bc215e17f1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:07.255187) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:07.256246) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:07.256520) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:07.393554) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:07.393842) ========= 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.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.2M=0.001s 2016-10-01 15:31:07 (14.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.506981 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:07.922895) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:07.923212) ========= 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.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.029931 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:07.973360) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-01 15:31:07.973672) ========= 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.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:07,950 truncating revision to first 12 chars 2016-10-01 15:31:07,950 Setting DEBUG logging. 2016-10-01 15:31:07,950 attempt 1/10 2016-10-01 15:31:07,950 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:08,958 unpacking tar archive at: mozilla-central-7c576fe3279d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.258803 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-01 15:31:09.253948) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:09.254273) ========= script_repo_revision: 7c576fe3279d87543f0a03b844eba7bc215e17f1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:09.254670) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:09.254958) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-01 15:31:09.276313) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-01 15:31:09.276719) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:09 INFO - MultiFileLogger online at 20161001 15:31:09 in /builds/slave/test 15:31:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:31:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:31:09 INFO - {'append_to_log': False, 15:31:09 INFO - 'base_work_dir': '/builds/slave/test', 15:31:09 INFO - 'blob_upload_branch': 'Firefox', 15:31:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:31:09 INFO - 'branch': 'Firefox', 15:31:09 INFO - 'buildbot_json_path': 'buildprops.json', 15:31:09 INFO - 'config_files': ('talos/mac_config.py',), 15:31:09 INFO - 'default_actions': ('clobber', 15:31:09 INFO - 'read-buildbot-config', 15:31:09 INFO - 'download-and-extract', 15:31:09 INFO - 'populate-webroot', 15:31:09 INFO - 'create-virtualenv', 15:31:09 INFO - 'install', 15:31:09 INFO - 'run-tests'), 15:31:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:31:09 INFO - 'download_minidump_stackwalk': True, 15:31:09 INFO - 'download_symbols': 'ondemand', 15:31:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:31:09 INFO - 'tooltool.py': '/tools/tooltool.py', 15:31:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:31:09 INFO - '/tools/misc-python/virtualenv.py')}, 15:31:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:31:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:31:09 INFO - 'installer_path': 'installer.exe', 15:31:09 INFO - 'log_level': 'info', 15:31:09 INFO - 'log_name': 'talos', 15:31:09 INFO - 'log_to_console': True, 15:31:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:31:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:31:09 INFO - 'opt_config_files': (), 15:31:09 INFO - 'pip_index': False, 15:31:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:31:09 INFO - 'cmd': ('bash', 15:31:09 INFO - '-c', 15:31:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:31:09 INFO - 'enabled': True, 15:31:09 INFO - 'halt_on_failure': False, 15:31:09 INFO - 'name': 'check_screen_resolution'},), 15:31:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:31:09 INFO - 'cmd': ('bash', 15:31:09 INFO - '-c', 15:31:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:31:09 INFO - 'enabled': True, 15:31:09 INFO - 'halt_on_failure': False, 15:31:09 INFO - 'name': 'check_screen_resolution'},), 15:31:09 INFO - 'run_cmd_checks_enabled': True, 15:31:09 INFO - 'sps_profile': False, 15:31:09 INFO - 'sps_profile_interval': 0, 15:31:09 INFO - 'suite': 'dromaeojs-e10s', 15:31:09 INFO - 'system_bits': '32', 15:31:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:31:09 INFO - 'title': 't-yosemite-r7-0029', 15:31:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:31:09 INFO - 'use_talos_json': True, 15:31:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:31:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:31:09 INFO - 'work_dir': 'build'} 15:31:09 INFO - [mozharness: 2016-10-01 22:31:09.389679Z] Running clobber step. 15:31:09 INFO - Running pre-action listener: _resource_record_pre_action 15:31:09 INFO - Running main action method: clobber 15:31:09 INFO - rmtree: /builds/slave/test/build 15:31:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:31:11 INFO - Running post-action listener: _resource_record_post_action 15:31:11 INFO - [mozharness: 2016-10-01 22:31:11.653292Z] Finished clobber step (success) 15:31:11 INFO - [mozharness: 2016-10-01 22:31:11.653563Z] Running read-buildbot-config step. 15:31:11 INFO - Running pre-action listener: _resource_record_pre_action 15:31:11 INFO - Running main action method: read_buildbot_config 15:31:11 INFO - Using buildbot properties: 15:31:11 INFO - { 15:31:11 INFO - "project": "", 15:31:11 INFO - "product": "firefox", 15:31:11 INFO - "script_repo_revision": "production", 15:31:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:31:11 INFO - "repository": "", 15:31:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:31:11 INFO - "buildid": "20161001140155", 15:31:11 INFO - "pgo_build": "False", 15:31:11 INFO - "basedir": "/builds/slave/test", 15:31:11 INFO - "buildnumber": 43, 15:31:11 INFO - "slavename": "t-yosemite-r7-0029", 15:31:11 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:31:11 INFO - "platform": "yosemite_r7", 15:31:11 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:31:11 INFO - "branch": "mozilla-central", 15:31:11 INFO - "repo_path": "mozilla-central", 15:31:11 INFO - "revision": "7c576fe3279d87543f0a03b844eba7bc215e17f1", 15:31:11 INFO - "stage_platform": "macosx64", 15:31:11 INFO - "builduid": "f80e5b28e5fa4ab6b86efd293eb80da9", 15:31:11 INFO - "slavebuilddir": "test" 15:31:11 INFO - } 15:31:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 15:31:11 INFO - Running post-action listener: _resource_record_post_action 15:31:11 INFO - [mozharness: 2016-10-01 22:31:11.659374Z] Finished read-buildbot-config step (success) 15:31:11 INFO - [mozharness: 2016-10-01 22:31:11.659519Z] Running download-and-extract step. 15:31:11 INFO - Running pre-action listener: _resource_record_pre_action 15:31:11 INFO - Running main action method: download_and_extract 15:31:11 INFO - mkdir: /builds/slave/test/build/tests 15:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:11 INFO - trying https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 15:31:11 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:11 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:12 INFO - Downloaded 1199 bytes. 15:31:12 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:31:12 INFO - Using the following test package requirements: 15:31:12 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:31:12 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:12 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 15:31:12 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:12 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 15:31:12 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:12 INFO - u'jsshell-mac.zip'], 15:31:12 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:12 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 15:31:12 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:31:12 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:12 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 15:31:12 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:12 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 15:31:12 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:12 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 15:31:12 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:31:12 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 15:31:12 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:31:12 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:12 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:12 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:13 INFO - Http code: 200 15:31:13 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 15:31:13 INFO - x-amz-cf-id: TwYNKYmC_ccKX7i5Vn2GQpHoM2LJ6vc6qKjmPhfZrWN48vdu1_Flng== 15:31:13 INFO - x-amz-version-id: jJMdINxSRczli9FwMrnCw2KZ4NYRLaYk 15:31:13 INFO - Expected file size: 16475685 15:31:13 INFO - Obtained file size: 16475685 15:31:20 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:20 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:20 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:20 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:21 INFO - Http code: 200 15:31:21 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 15:31:21 INFO - x-amz-cf-id: 9Ro99LgeE_zyfjluJTcOpYy-YomPYJSdT2k-xnKiREaQw84SRevXRw== 15:31:21 INFO - x-amz-version-id: jJMdINxSRczli9FwMrnCw2KZ4NYRLaYk 15:31:21 INFO - Expected file size: 16475685 15:31:21 INFO - Obtained file size: 16475685 15:31:27 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:27 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:27 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:28 INFO - Http code: 200 15:31:28 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 15:31:28 INFO - x-amz-cf-id: Qqu8NKiYUux4lYRQZBfpyHtkXSuMw6RwOdTfJPid3xQ5oRISFE-y5Q== 15:31:28 INFO - x-amz-version-id: sCW5HqaOW.c0I__eMDsUeaQ03u9DBWZS 15:31:29 INFO - Expected file size: 14408522 15:31:29 INFO - Obtained file size: 14408522 15:31:29 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:29 INFO - trying https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:31:29 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:29 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:32 INFO - Downloaded 111818313 bytes. 15:31:32 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:32 INFO - mkdir: /builds/slave/test/properties 15:31:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:31:32 INFO - Writing to file /builds/slave/test/properties/build_url 15:31:32 INFO - Contents: 15:31:32 INFO - build_url:https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:31:32 INFO - Running post-action listener: _resource_record_post_action 15:31:32 INFO - Running post-action listener: set_extra_try_arguments 15:31:32 INFO - [mozharness: 2016-10-01 22:31:32.790812Z] Finished download-and-extract step (success) 15:31:32 INFO - [mozharness: 2016-10-01 22:31:32.790954Z] Running populate-webroot step. 15:31:32 INFO - Running pre-action listener: _resource_record_pre_action 15:31:32 INFO - Running main action method: populate_webroot 15:31:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:31:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:31:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:31:32 INFO - '--tppagecycles', 15:31:32 INFO - '7'], 15:31:32 INFO - 'tests': ['tsvgm']}}, 15:31:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:31:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:31:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:31:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:31:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:31:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:31:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:32 INFO - 'talos_options': ['--disable-e10s'], 15:31:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:31:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:31:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:32 INFO - 'talos_options': ['--disable-e10s'], 15:31:32 INFO - 'tests': ['damp', 'tps']}, 15:31:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:32 INFO - 'tests': ['damp', 'tps']}, 15:31:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:31:32 INFO - 'tests': ['dromaeo_dom']}, 15:31:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:31:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:31:32 INFO - 'tests': ['basic_compositor_video']}, 15:31:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:31:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:31:32 INFO - 'tests': ['a11yr', 15:31:32 INFO - 'ts_paint', 15:31:32 INFO - 'tpaint', 15:31:32 INFO - 'sessionrestore', 15:31:32 INFO - 'sessionrestore_no_auto_restore', 15:31:32 INFO - 'tabpaint']}, 15:31:32 INFO - 'other-e10s': {'tests': ['a11yr', 15:31:32 INFO - 'ts_paint', 15:31:32 INFO - 'tpaint', 15:31:32 INFO - 'sessionrestore', 15:31:32 INFO - 'sessionrestore_no_auto_restore', 15:31:32 INFO - 'tabpaint']}, 15:31:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:31:32 INFO - 'ts_paint', 15:31:32 INFO - 'tpaint', 15:31:32 INFO - 'sessionrestore', 15:31:32 INFO - 'sessionrestore_no_auto_restore', 15:31:32 INFO - 'tabpaint']}, 15:31:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:31:32 INFO - 'ts_paint', 15:31:32 INFO - 'tpaint', 15:31:32 INFO - 'sessionrestore', 15:31:32 INFO - 'sessionrestore_no_auto_restore', 15:31:32 INFO - 'tabpaint']}, 15:31:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:31:32 INFO - 'tests': ['a11yr', 15:31:32 INFO - 'ts_paint', 15:31:32 INFO - 'tpaint', 15:31:32 INFO - 'sessionrestore', 15:31:32 INFO - 'sessionrestore_no_auto_restore', 15:31:32 INFO - 'tabpaint']}, 15:31:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:31:32 INFO - 'tests': ['a11yr', 15:31:32 INFO - 'ts_paint', 15:31:32 INFO - 'tpaint', 15:31:32 INFO - 'sessionrestore', 15:31:32 INFO - 'sessionrestore_no_auto_restore', 15:31:32 INFO - 'tabpaint']}, 15:31:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:31:32 INFO - 'tests': ['tsvgx', 15:31:32 INFO - 'tsvgr_opacity', 15:31:32 INFO - 'tart', 15:31:32 INFO - 'tscrollx', 15:31:32 INFO - 'cart']}, 15:31:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:31:32 INFO - 'tsvgr_opacity', 15:31:32 INFO - 'tart', 15:31:32 INFO - 'tscrollx', 15:31:32 INFO - 'cart']}, 15:31:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:32 INFO - 'talos_options': ['--disable-e10s'], 15:31:32 INFO - 'tests': ['tp5o']}, 15:31:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:32 INFO - 'tests': ['tp5o']}, 15:31:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:32 INFO - 'talos_options': ['--disable-e10s', 15:31:32 INFO - '--xperf_path', 15:31:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:31:32 INFO - 'tests': ['tp5n']}, 15:31:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:32 INFO - 'talos_options': ['--xperf_path', 15:31:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:31:32 INFO - 'tests': ['tp5n']}}, 15:31:32 INFO - 'talos.zip': {'path': '', 15:31:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:31:32 INFO - Running post-action listener: _resource_record_post_action 15:31:32 INFO - [mozharness: 2016-10-01 22:31:32.809580Z] Finished populate-webroot step (success) 15:31:32 INFO - [mozharness: 2016-10-01 22:31:32.809717Z] Running create-virtualenv step. 15:31:32 INFO - Running pre-action listener: _resource_record_pre_action 15:31:32 INFO - Running main action method: create_virtualenv 15:31:32 INFO - Creating virtualenv /builds/slave/test/build/venv 15:31:32 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:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:31:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:31:32 INFO - Using real prefix '/tools/python27' 15:31:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:31:33 INFO - Installing distribute.............................................................................................................................................................................................done. 15:31:36 INFO - Installing pip.................done. 15:31:36 INFO - Return code: 0 15:31:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:31:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:31:36 INFO - Reading from file tmpfile_stdout 15:31:36 INFO - Output received: 15:31:36 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:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:31:36 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:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:36 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:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:36 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 0x109ba0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b16570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82224c5810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b4d30>, '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 0x1097dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099acb20>, '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.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:36 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:36 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:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rFIeMoBTjJ/Render', 15:31:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:36 INFO - 'HOME': '/Users/cltbld', 15:31:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:36 INFO - 'LOGNAME': 'cltbld', 15:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:36 INFO - 'MOZ_NO_REMOTE': '1', 15:31:36 INFO - 'NO_EM_RESTART': '1', 15:31:36 INFO - 'PAGER': '/bin/cat', 15:31:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:36 INFO - 'PWD': '/builds/slave/test', 15:31:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:36 INFO - 'SHELL': '/bin/bash', 15:31:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdLmRgxy6E/Listeners', 15:31:36 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 15:31:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:36 INFO - 'USER': 'cltbld', 15:31:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:36 INFO - 'XPC_FLAGS': '0x0', 15:31:36 INFO - 'XPC_SERVICE_NAME': '0', 15:31:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:37 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:37 INFO - Cleaning up... 15:31:37 INFO - Return code: 0 15:31:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109ba0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b16570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82224c5810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b4d30>, '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 0x1097dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099acb20>, '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.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:31:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rFIeMoBTjJ/Render', 15:31:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:37 INFO - 'HOME': '/Users/cltbld', 15:31:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:37 INFO - 'LOGNAME': 'cltbld', 15:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:37 INFO - 'MOZ_NO_REMOTE': '1', 15:31:37 INFO - 'NO_EM_RESTART': '1', 15:31:37 INFO - 'PAGER': '/bin/cat', 15:31:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:37 INFO - 'PWD': '/builds/slave/test', 15:31:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:37 INFO - 'SHELL': '/bin/bash', 15:31:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdLmRgxy6E/Listeners', 15:31:37 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 15:31:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:37 INFO - 'USER': 'cltbld', 15:31:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:37 INFO - 'XPC_FLAGS': '0x0', 15:31:37 INFO - 'XPC_SERVICE_NAME': '0', 15:31:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:37 INFO - Downloading/unpacking psutil>=3.1.1 15:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:31:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:31:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:31:41 INFO - Installing collected packages: psutil 15:31:41 INFO - Running setup.py install for psutil 15:31:41 INFO - building 'psutil._psutil_osx' extension 15:31:41 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:41 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:41 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:42 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:42 INFO - building 'psutil._psutil_posix' extension 15:31:42 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:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:31:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:31:42 INFO - ^ 15:31:42 INFO - 1 warning generated. 15:31:42 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:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:31:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:31:42 INFO - Successfully installed psutil 15:31:42 INFO - Cleaning up... 15:31:42 INFO - Return code: 0 15:31:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ba0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b16570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82224c5810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b4d30>, '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 0x1097dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099acb20>, '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.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:31:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:31:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rFIeMoBTjJ/Render', 15:31:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:42 INFO - 'HOME': '/Users/cltbld', 15:31:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:42 INFO - 'LOGNAME': 'cltbld', 15:31:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:42 INFO - 'MOZ_NO_REMOTE': '1', 15:31:42 INFO - 'NO_EM_RESTART': '1', 15:31:42 INFO - 'PAGER': '/bin/cat', 15:31:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:42 INFO - 'PWD': '/builds/slave/test', 15:31:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:42 INFO - 'SHELL': '/bin/bash', 15:31:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdLmRgxy6E/Listeners', 15:31:42 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 15:31:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:42 INFO - 'USER': 'cltbld', 15:31:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:42 INFO - 'XPC_FLAGS': '0x0', 15:31:42 INFO - 'XPC_SERVICE_NAME': '0', 15:31:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:31:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:31:45 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:45 INFO - Installing collected packages: mozsystemmonitor 15:31:45 INFO - Running setup.py install for mozsystemmonitor 15:31:45 INFO - Successfully installed mozsystemmonitor 15:31:45 INFO - Cleaning up... 15:31:45 INFO - Return code: 0 15:31:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109ba0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b16570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82224c5810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b4d30>, '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 0x1097dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099acb20>, '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.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:31:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:31:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rFIeMoBTjJ/Render', 15:31:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:45 INFO - 'HOME': '/Users/cltbld', 15:31:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:45 INFO - 'LOGNAME': 'cltbld', 15:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:45 INFO - 'MOZ_NO_REMOTE': '1', 15:31:45 INFO - 'NO_EM_RESTART': '1', 15:31:45 INFO - 'PAGER': '/bin/cat', 15:31:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:45 INFO - 'PWD': '/builds/slave/test', 15:31:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:45 INFO - 'SHELL': '/bin/bash', 15:31:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdLmRgxy6E/Listeners', 15:31:45 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 15:31:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:45 INFO - 'USER': 'cltbld', 15:31:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:45 INFO - 'XPC_FLAGS': '0x0', 15:31:45 INFO - 'XPC_SERVICE_NAME': '0', 15:31:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:45 INFO - Downloading/unpacking jsonschema==2.5.1 15:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:31:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:48 INFO - Downloading functools32-3.2.3-2.tar.gz 15:31:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:31:48 INFO - warning: no files found matching '*.txt' 15:31:48 INFO - no previously-included directories found matching 'build' 15:31:48 INFO - no previously-included directories found matching 'dist' 15:31:48 INFO - no previously-included directories found matching '.git*' 15:31:48 INFO - Installing collected packages: jsonschema, functools32 15:31:48 INFO - Running setup.py install for functools32 15:31:48 INFO - warning: no files found matching '*.txt' 15:31:48 INFO - no previously-included directories found matching 'build' 15:31:48 INFO - no previously-included directories found matching 'dist' 15:31:48 INFO - no previously-included directories found matching '.git*' 15:31:48 INFO - Successfully installed jsonschema functools32 15:31:48 INFO - Cleaning up... 15:31:48 INFO - Return code: 0 15:31:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109ba0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b16570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82224c5810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b4d30>, '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 0x1097dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099acb20>, '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.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:31:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:31:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rFIeMoBTjJ/Render', 15:31:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:48 INFO - 'HOME': '/Users/cltbld', 15:31:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:48 INFO - 'LOGNAME': 'cltbld', 15:31:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:48 INFO - 'MOZ_NO_REMOTE': '1', 15:31:48 INFO - 'NO_EM_RESTART': '1', 15:31:48 INFO - 'PAGER': '/bin/cat', 15:31:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:48 INFO - 'PWD': '/builds/slave/test', 15:31:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:48 INFO - 'SHELL': '/bin/bash', 15:31:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdLmRgxy6E/Listeners', 15:31:48 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 15:31:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:48 INFO - 'USER': 'cltbld', 15:31:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:48 INFO - 'XPC_FLAGS': '0x0', 15:31:48 INFO - 'XPC_SERVICE_NAME': '0', 15:31:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:31:49 INFO - Cleaning up... 15:31:49 INFO - Return code: 0 15:31:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:49 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 0x109ba0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b16570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82224c5810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b4d30>, '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 0x1097dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099acb20>, '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.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:49 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:49 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:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rFIeMoBTjJ/Render', 15:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:49 INFO - 'HOME': '/Users/cltbld', 15:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:49 INFO - 'LOGNAME': 'cltbld', 15:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:49 INFO - 'MOZ_NO_REMOTE': '1', 15:31:49 INFO - 'NO_EM_RESTART': '1', 15:31:49 INFO - 'PAGER': '/bin/cat', 15:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:49 INFO - 'PWD': '/builds/slave/test', 15:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:49 INFO - 'SHELL': '/bin/bash', 15:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdLmRgxy6E/Listeners', 15:31:49 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 15:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:49 INFO - 'USER': 'cltbld', 15:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:49 INFO - 'XPC_FLAGS': '0x0', 15:31:49 INFO - 'XPC_SERVICE_NAME': '0', 15:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:49 INFO - Downloading/unpacking blobuploader==1.2.4 15:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:51 INFO - Downloading blobuploader-1.2.4.tar.gz 15:31:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:31:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:31:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:53 INFO - Downloading docopt-0.6.1.tar.gz 15:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:31:53 INFO - Installing collected packages: blobuploader, requests, docopt 15:31:53 INFO - Running setup.py install for blobuploader 15:31:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:31:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:31:53 INFO - Running setup.py install for requests 15:31:53 INFO - Running setup.py install for docopt 15:31:54 INFO - Successfully installed blobuploader requests docopt 15:31:54 INFO - Cleaning up... 15:31:54 INFO - Return code: 0 15:31:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:54 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 0x109ba0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b16570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82224c5810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b4d30>, '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 0x1097dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099acb20>, '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.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:54 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:54 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:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rFIeMoBTjJ/Render', 15:31:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:54 INFO - 'HOME': '/Users/cltbld', 15:31:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:54 INFO - 'LOGNAME': 'cltbld', 15:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:54 INFO - 'MOZ_NO_REMOTE': '1', 15:31:54 INFO - 'NO_EM_RESTART': '1', 15:31:54 INFO - 'PAGER': '/bin/cat', 15:31:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:54 INFO - 'PWD': '/builds/slave/test', 15:31:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:54 INFO - 'SHELL': '/bin/bash', 15:31:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdLmRgxy6E/Listeners', 15:31:54 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 15:31:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:54 INFO - 'USER': 'cltbld', 15:31:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:54 INFO - 'XPC_FLAGS': '0x0', 15:31:54 INFO - 'XPC_SERVICE_NAME': '0', 15:31:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:31:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ZuUqb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:31:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-evzrkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:31:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ugARyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:31:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-_2p2sr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:31:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-CyoXZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:31:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-8oyHXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:31:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-vLfXbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:31:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-FghEvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:31:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-jeUwwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:31:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-RMmZY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:31:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-xP5TyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:31:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-4Sf0E6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:31:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-CoYvp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:31:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-5qPAp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:31:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zHj11C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:31:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-M64IyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:31:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-vcp9eM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:31:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:31:56 INFO - Running setup.py install for manifestparser 15:31:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:31:56 INFO - Running setup.py install for mozcrash 15:31:56 INFO - Running setup.py install for mozdebug 15:31:56 INFO - Running setup.py install for mozdevice 15:31:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:31:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:31:56 INFO - Running setup.py install for mozfile 15:31:56 INFO - Running setup.py install for mozhttpd 15:31:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:31:57 INFO - Running setup.py install for mozinfo 15:31:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:31:57 INFO - Running setup.py install for mozInstall 15:31:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:31:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:31:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:31:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:31:57 INFO - Running setup.py install for mozleak 15:31:57 INFO - Running setup.py install for mozlog 15:31:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:31:57 INFO - Running setup.py install for moznetwork 15:31:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:31:57 INFO - Running setup.py install for mozprocess 15:31:58 INFO - Running setup.py install for mozprofile 15:31:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:31:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:31:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:31:58 INFO - Running setup.py install for mozrunner 15:31:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:31:58 INFO - Running setup.py install for mozscreenshot 15:31:58 INFO - Running setup.py install for moztest 15:31:58 INFO - Running setup.py install for mozversion 15:31:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:31:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:31:58 INFO - Cleaning up... 15:31:58 INFO - Return code: 0 15:31:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:58 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 0x109ba0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b16570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82224c5810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b4d30>, '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 0x1097dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099acb20>, '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.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:58 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:58 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:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rFIeMoBTjJ/Render', 15:31:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:58 INFO - 'HOME': '/Users/cltbld', 15:31:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:58 INFO - 'LOGNAME': 'cltbld', 15:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:58 INFO - 'MOZ_NO_REMOTE': '1', 15:31:58 INFO - 'NO_EM_RESTART': '1', 15:31:58 INFO - 'PAGER': '/bin/cat', 15:31:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:58 INFO - 'PWD': '/builds/slave/test', 15:31:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:58 INFO - 'SHELL': '/bin/bash', 15:31:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdLmRgxy6E/Listeners', 15:31:58 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 15:31:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:58 INFO - 'USER': 'cltbld', 15:31:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:58 INFO - 'XPC_FLAGS': '0x0', 15:31:58 INFO - 'XPC_SERVICE_NAME': '0', 15:31:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:31:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-0X4755-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:31:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:31:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-rmeGrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:31:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:31:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-1I3gWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:31:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:31:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-bCGYjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:31:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:31:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-lcXVwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:31:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:31:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-N1PfZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:31:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:31:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-NpmQZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:31:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:31:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zmvpGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:31:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:31:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-lQEsMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:32:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-xFCjzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:32:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Sfihzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:32:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-PvZjqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:32:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-6srACu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:32:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-r29kxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-AHIP9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:32:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-hllGyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:32:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zgVqVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:32:00 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:32:00 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:32:00 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:32:00 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:32:00 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:32:00 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:32:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:32:00 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:32:00 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:00 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:32:00 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:32:00 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:32:00 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:03 INFO - Downloading blessings-1.6.tar.gz 15:32:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:32:03 INFO - Installing collected packages: blessings 15:32:03 INFO - Running setup.py install for blessings 15:32:03 INFO - Successfully installed blessings 15:32:03 INFO - Cleaning up... 15:32:03 INFO - Return code: 0 15:32:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:32:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:32:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:32:04 INFO - Reading from file tmpfile_stdout 15:32:04 INFO - Current package versions: 15:32:04 INFO - blessings == 1.6 15:32:04 INFO - blobuploader == 1.2.4 15:32:04 INFO - docopt == 0.6.1 15:32:04 INFO - functools32 == 3.2.3-2 15:32:04 INFO - jsonschema == 2.5.1 15:32:04 INFO - manifestparser == 1.1 15:32:04 INFO - mozInstall == 1.12 15:32:04 INFO - mozcrash == 0.17 15:32:04 INFO - mozdebug == 0.1 15:32:04 INFO - mozdevice == 0.48 15:32:04 INFO - mozfile == 1.2 15:32:04 INFO - mozhttpd == 0.7 15:32:04 INFO - mozinfo == 0.9 15:32:04 INFO - mozleak == 0.1 15:32:04 INFO - mozlog == 3.4 15:32:04 INFO - moznetwork == 0.27 15:32:04 INFO - mozprocess == 0.23 15:32:04 INFO - mozprofile == 0.28 15:32:04 INFO - mozrunner == 6.12 15:32:04 INFO - mozscreenshot == 0.1 15:32:04 INFO - mozsystemmonitor == 0.3 15:32:04 INFO - moztest == 0.7 15:32:04 INFO - mozversion == 1.4 15:32:04 INFO - psutil == 3.1.1 15:32:04 INFO - requests == 1.2.3 15:32:04 INFO - wsgiref == 0.1.2 15:32:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:04 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 0x109ba0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b16570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82224c5810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b4d30>, '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 0x1097dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099acb20>, '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.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:04 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:04 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:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rFIeMoBTjJ/Render', 15:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:04 INFO - 'HOME': '/Users/cltbld', 15:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:04 INFO - 'LOGNAME': 'cltbld', 15:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:04 INFO - 'MOZ_NO_REMOTE': '1', 15:32:04 INFO - 'NO_EM_RESTART': '1', 15:32:04 INFO - 'PAGER': '/bin/cat', 15:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:04 INFO - 'PWD': '/builds/slave/test', 15:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:04 INFO - 'SHELL': '/bin/bash', 15:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdLmRgxy6E/Listeners', 15:32:04 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 15:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:04 INFO - 'USER': 'cltbld', 15:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:04 INFO - 'XPC_FLAGS': '0x0', 15:32:04 INFO - 'XPC_SERVICE_NAME': '0', 15:32:04 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): 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:04 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:04 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:04 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:04 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:04 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:04 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:04 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:04 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:04 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:04 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:04 INFO - Cleaning up... 15:32:04 INFO - Return code: 0 15:32:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ba0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b16570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82224c5810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b4d30>, '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 0x1097dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099acb20>, '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.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rFIeMoBTjJ/Render', 15:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:04 INFO - 'HOME': '/Users/cltbld', 15:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:04 INFO - 'LOGNAME': 'cltbld', 15:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:04 INFO - 'MOZ_NO_REMOTE': '1', 15:32:04 INFO - 'NO_EM_RESTART': '1', 15:32:04 INFO - 'PAGER': '/bin/cat', 15:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:04 INFO - 'PWD': '/builds/slave/test', 15:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:04 INFO - 'SHELL': '/bin/bash', 15:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdLmRgxy6E/Listeners', 15:32:04 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 15:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:04 INFO - 'USER': 'cltbld', 15:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:04 INFO - 'XPC_FLAGS': '0x0', 15:32:04 INFO - 'XPC_SERVICE_NAME': '0', 15:32:04 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.667242Z] Finished create-virtualenv step (success) 15:32:04 INFO - [mozharness: 2016-10-01 22:32:04.668207Z] 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:05 INFO - Reading from file tmpfile_stdout 15:32:05 INFO - Output received: 15:32:05 INFO - Usage: mozinstall [options] installer 15:32:05 INFO - Options: 15:32:05 INFO - -h, --help show this help message and exit 15:32:05 INFO - -d DEST, --destination=DEST 15:32:05 INFO - Directory to install application into. [default: 15:32:05 INFO - "/builds/slave/test"] 15:32:05 INFO - --app=APP Application being installed. [default: firefox] 15:32:05 INFO - mkdir: /builds/slave/test/build/application 15:32:05 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:05 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:39 INFO - Reading from file tmpfile_stdout 15:32:39 INFO - Output received: 15:32:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:32:39 INFO - Running post-action listener: _resource_record_post_action 15:32:39 INFO - [mozharness: 2016-10-01 22:32:39.801830Z] Finished install step (success) 15:32:39 INFO - [mozharness: 2016-10-01 22:32:39.801960Z] Running run-tests step. 15:32:39 INFO - Running pre-action listener: _resource_record_pre_action 15:32:39 INFO - Running main action method: run_tests 15:32:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:32:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:32:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:32:39 INFO - 2016-10-01 15:32:39.816 screenresolution[1653:8452] starting screenresolution argv=screenresolution get 15:32:39 INFO - 2016-10-01 15:32:39.819 screenresolution[1653:8452] Display 0: 1600x1200x32@60 15:32:39 INFO - 2016-10-01 15:32:39.825 screenresolution[1654:8454] starting screenresolution argv=screenresolution list 15:32:39 INFO - Available Modes on Display 0 15:32:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:32:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:32:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:32:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:32:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:32:40 INFO - Intel Iris: 15:32:40 INFO - Chipset Model: Intel Iris 15:32:40 INFO - Type: GPU 15:32:40 INFO - Bus: Built-In 15:32:40 INFO - VRAM (Dynamic, Max): 1536 MB 15:32:40 INFO - Vendor: Intel (0x8086) 15:32:40 INFO - Device ID: 0x0a2e 15:32:40 INFO - Revision ID: 0x0009 15:32:40 INFO - Displays: 15:32:40 INFO - Display: 15:32:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:32:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:32:40 INFO - Display Serial Number: 1600x1200 60 15:32:40 INFO - Main Display: Yes 15:32:40 INFO - Mirror: Off 15:32:40 INFO - Online: Yes 15:32:40 INFO - Rotation: Supported 15:32:40 INFO - Return code: 0 15:32:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:32:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:32:40 INFO - Python 2.7.3 15:32:40 INFO - Return code: 0 15:32:40 INFO - grabbing minidump binary from tooltool 15:32: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:32:40 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 0x1099b4d30>, '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 0x1097dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099acb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:32:40 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:40 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:40 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:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:32:40 INFO - Return code: 0 15:32:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:32:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:32:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:32:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:32:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:32:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:32:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:32:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rFIeMoBTjJ/Render', 15:32:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:40 INFO - 'HOME': '/Users/cltbld', 15:32:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:40 INFO - 'LOGNAME': 'cltbld', 15:32:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:32:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:32:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:40 INFO - 'MOZ_NO_REMOTE': '1', 15:32:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:32:40 INFO - 'NO_EM_RESTART': '1', 15:32:40 INFO - 'PAGER': '/bin/cat', 15:32:40 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:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:40 INFO - 'PWD': '/builds/slave/test', 15:32:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:32:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:40 INFO - 'SHELL': '/bin/bash', 15:32:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdLmRgxy6E/Listeners', 15:32:40 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 15:32:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:40 INFO - 'USER': 'cltbld', 15:32:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:40 INFO - 'XPC_FLAGS': '0x0', 15:32:40 INFO - 'XPC_SERVICE_NAME': '0', 15:32:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:32:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:32:40 INFO - mozversion application_buildid: 20161001140155 15:32:40 INFO - mozversion application_changeset: 7c576fe3279d87543f0a03b844eba7bc215e17f1 15:32:40 INFO - mozversion application_display_name: Nightly 15:32:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:32:40 INFO - mozversion application_name: Firefox 15:32:40 INFO - mozversion application_remotingname: firefox 15:32:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:32:40 INFO - mozversion application_vendor: Mozilla 15:32:40 INFO - mozversion application_version: 52.0a1 15:32:40 INFO - mozversion platform_buildid: 20161001140155 15:32:40 INFO - mozversion platform_changeset: 7c576fe3279d87543f0a03b844eba7bc215e17f1 15:32:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:32:40 INFO - mozversion platform_version: 52.0a1 15:32:40 INFO - using testdate: 1475361160 15:32:40 INFO - actual date: 1475361160 15:32:40 INFO - starting webserver on 'localhost:49266' 15:32:40 INFO - SUITE-START | Running 2 tests 15:32:40 INFO - TEST-START | dromaeo_css 15:32:40 INFO - operating with platform_type : mac_ 15:32:40 INFO - Initialising browser for dromaeo_css test... 15:32:40 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmphO_e00/profile http://localhost:49266/getInfo.html) 15:32:43 INFO - PROCESS | 1664 | 2016-10-01 15:32:43.138 plugin-container[1666:8633] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 15:32:43 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:32:43 INFO - PROCESS | 1664 | 2016-10-01 15:32:43.141 plugin-container[1666:8633] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a2b, name = 'com.apple.CFPasteboardClient' 15:32:43 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:32:43 INFO - PROCESS | 1664 | 2016-10-01 15:32:43.141 plugin-container[1666:8633] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:32:43 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 15:32:43 INFO - PROCESS | 1664 | colorDepth:24 15:32:43 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 15:32:43 INFO - PROCESS | 1664 | __metrics 15:32:43 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 15:32:44 INFO - TEST-INFO | 1664: exit 0 15:32:44 INFO - Browser initialized. 15:32:44 INFO - Running cycle 1/1 for dromaeo_css test... 15:32:44 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmphO_e00/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:32:46 INFO - PROCESS | 1668 | 2016-10-01 15:32:46.475 plugin-container[1669:8756] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1f, name = 'com.apple.tsm.portname' 15:32:46 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:32:46 INFO - PROCESS | 1668 | 2016-10-01 15:32:46.479 plugin-container[1669:8756] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f07, name = 'com.apple.CFPasteboardClient' 15:32:46 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:32:46 INFO - PROCESS | 1668 | 2016-10-01 15:32:46.479 plugin-container[1669:8756] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:32:56 INFO - PROCESS | 1668 | RSS: Main: 240312320 15:32:56 INFO - PROCESS | 1668 | 15:33:45 INFO - PROCESS | 1668 | 1475361225769 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:58 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 15:36:00 INFO - PROCESS | 1668 | RSS: Main: 253857792 15:36:00 INFO - PROCESS | 1668 | 15:39:02 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 15:39:03 INFO - PROCESS | 1668 | RSS: Main: 267931648 15:39:03 INFO - PROCESS | 1668 | 15:42:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 15:42:05 INFO - PROCESS | 1668 | RSS: Main: 270594048 15:42:05 INFO - PROCESS | 1668 | 15:45:07 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 15:45:08 INFO - PROCESS | 1668 | RSS: Main: 271323136 15:45:08 INFO - PROCESS | 1668 | 15:48:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 15:48:11 INFO - PROCESS | 1668 | RSS: Main: 276520960 15:48:11 INFO - PROCESS | 1668 | 15:51:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 15:51:14 INFO - PROCESS | 1668 | RSS: Main: 279244800 15:51:14 INFO - PROCESS | 1668 | 15:51:14 INFO - PROCESS | 1668 | __start_tp_report 15:51:14 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 15:51:14 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 15:51:14 INFO - PROCESS | 1668 | |i|pagename|runs| 15:51:14 INFO - PROCESS | 1668 | |0;dojo.html;3300.471924492079;3332.067021234055;3347.2803347280346;3351.0617027232374;3367.494875768636;5493.642913210649;5519.2273081768535;5550.833474995751;5562.694051827148;5616.494515493605;4576.862694119173;5496.560275177986;5557.3331200256025;5577.386487486373;5613.354464236614;5058.165402708567;5111.437741848394;5163.948360516403;5198.662086964354;5204.9739751301195;5024.246363045536;5198.168293073109;5198.194279886624;5233.502817232356;5238.266642670029;4240.257954857888;5013.498650134994;5084.186530155194;5189.766460509249;5199.844004679847;5019.723915184663;5084.694918304876;5119.283300337956;5129.025485157808;5180.766865491062;5055.646104772667;5057.848264552069;5073.239014147871;5077.822276220315;5130.07658621448;5453.072977593819;5497;5595.7481913313995;5626.212330273765;5641.294838145232;5371.543418809406;5932.932072226998;5976.8505787355225;6136.6011209271255;6156.630602163839;6191.102290167901;6194.070889366586;6197.566170368031;6218.720157592874;6220.222472190976;4355.693292012415;5006.5494105530315;5715.428457154335;5723.484886360247;5731.684757338345;5022.824201152942;5805.129230615357;6114.2357205349335;6139.553468857682;6176.227971503562;4871.3667223261455;5953.434423729739;5998.400159983967;6003.339632640406;6020.518358531307;4903.828366007173;6073.908891366633;6092.360302168208;6115.571909966348;6124.479419249414;5939.910901336483;5997.340292567814;6025.969870150621;6031.758729650853;6032.125341825498;5980.55145864062;6082.665453400017;6086.087086936906;6091.390860913873;6115.510759139258;4143.91712165755;4233.491980962242;4827.42070951481;4829.5653391194965;4880.341153944172;4274.850380236677;4281.336392859112;4314.094040251583;4341.60925516705;4369.497507786646;5681.318241811009;5683.403242659544;5689.089745640677;5693.658380497183;5747.597668163272;5000.574951129122;6084.178635884187;6144.354842741448;6159;6163.599366041193;5549.805756798492;5624.353199382042;5630.239917611152;5631.662100273997;5715.914261286084;5274.182501712241;5321.281626980308;5333.786648534093;5361.51746342831;5363.678179309254;6202.34875338092;6212.689365531705;6226.283977342573;6230.7507699691505;6240.812775616557;6018.21763170785;6116.35778243278;6193.102000209854;6202.5658203925295;6215.96892015537;6156.322804491592;6165.167702360124;6189.226346706661;6213.96893015532;6215.627062376272;6007.948608993496;6043.486303664238;6043.909341359795;6072.90890636632;6110;5972.014617588049;6146.846328841814;6154.815355539341;6174.6912654368;6182.319944805978;5725.19847221381;5790.449907258803;5925.111233315038;5973.790917317873;6089.756409743561;5953.1070339449425;5984.162217289496;5992.550558708202;6000.489958353589;6028.367021462684;5655.660660360391;5707.258056452634;5743.195952566728;5832.50423713989;5871.588988770745;5913.822585322448;5934.139549765347;5934.961381758261;5944.791932282349;6006.789762358297;5713.800016999385;5757.3379061406795;5764.106563482667;5781.682007489629;5788.565857560784 15:51:14 INFO - PROCESS | 1668 | |1;ext.html;480.63471761461324;522.5714913770709;524.4781442464748;524.748120901967;535.7830078818077;37341.43987840181;40779.38830917539;40806.7959660202;40808.38787418183;40873.97815054618;37628.05929851748;41493.17013659725;41866.58133418665;41916.79041604795;42019.15961680764;7904.407169462284;8305.12796156404;8556.71649252611;9685.176759975411;9772.169365603932;9740.220782337432;9834.803303933953;9836.3606365586;9844.655437059704;9876.802463950717;9831.950840245789;9863.753406164831;9872.753181170492;9877.70366888991;9878.555465004056;9785.217182625409;9833.606655733762;9835.360701554448;9859.359141655841;9879.012098790135;9639.277054220962;9699.563519641633;9713.465759383302;9732.90267097334;9741.512924353825;39928.600713993066;40112.59887401147;40346.19307613831;40396;40410.79794601008;3390.9830450847835;3436.3642726095754;3530.2762933598806;3556.395412779834;4008.719389642726;29126.126216213546;31081.91213307546;31592.684073159206;31958;31969.52045719316;2993.176876358997;3155.9347247667392;3323.7341012718925;3372.7301815854917;3372.7470439717117;426595.867020666;476936;500106;500748;505197;60071;60373;60480;60504.69747651234;60790.40119985002;62750.686246568475;62866.68566657137;62980.685096575144;63554.68222658857;63608;16179.352825887068;16341.019538827708;16384.508464746075;16390.754138687927;16417.507474775775;12654.557090501788;12719.491220351267;12727.554535591395;12741.745165096832;12747.490100396066;15917.204139792964;16620.501384958472;16634.33462661504;16680.666386672197;16700.6659866802;6528.37980391862;7130.572165670076;7176.677049532784;7191.7842464726145;7194.100737407824;821.6959724901825;981.6564202529057;994.4480813148772;1015.9847602285971;1049.9317544359592;28018.71981280161;28978.13065608035;29545.704542954725;29844.80620775188;30365.78536858501;918.9156794981985;1050.4170185547096;1057.6932689519954;1059.6397224943403;1068.4230515521688;15204.923975380052;15363.769543456858;15404.46084387041;15487.225638718244;15562.922185389001;550359;588797;630781.8460907666;632422;633698;55591.44408555863;55816.72091639597;56017.719911400185;56087.43912560822;56296;1283.595667364777;1341.4902337111835;1350.8311461067563;1356.938937747784;1361.5030513862368;29110.56334154947;29304;29313.85343073271;29785.702142978294;30088.54867177038;48246.75876620735;48693.0261394763;48776;48797.512024879295;48801.511984881115;471911;474553;474822;475804;477676;38396.808015959745;39493.60506394899;39604.80197598993;39626.40560391539;39704;42118.73643790691;44456.66630001105;44852.77573612111;46871;48240.276395854795;38228.85313440601;39417.2116557673;40207.19585608213;40600.1879962405;41017.1796564073;28552.857235713687;31319.68680313259;33153.83423082869;39734.40398394084;40171.1965760689;28541.571876421454;29116.854415727787;33355.49966750549;33661.32677346488;38237.426438604;28973.855130724212;33581.99254022383;34244.14389640279;34597.827010864785;34852.5978473337;20084.89957550203;25029.249122526355;25217.87391063033;25609.055533075887;25733.742662573135 15:51:14 INFO - PROCESS | 1668 | |2;jquery.html;360.78713559000187;368.4068649474346;369.98705045323413;374.6403452685422;391.9039835240367;17893.7315940261;19515.41453756386;20180.69728954067;20476.283330083468;20753.58492830146;17058.82941170588;18891.90554047231;20026.49933751657;20488.69266960997;20685.482862928435;5766.596338256324;5871.119332100197;6163.383661633846;6193.0400787877725;6198.535109866755;6020;6082.452579267865;6090.786822461215;6137.969310153443;6146.200993870791;6089.878202435971;6128.6322820630685;6137.8158655240195;6732.98010596819;6849.863002739942;6423.8394040148905;6767.458603311723;6818.693158807866;6821.999999999975;6830.214525329601;5917.260342457193;5965.522758179335;5976.4919981801595;5984.461398474158;6002.399760024006;13309.467621295136;13601.931990339985;20251.696224556643;21773.891130544245;25193.244202673948;2141.646628306328;2282.988585057081;2434.476587533671;2561;2645.915945648768;9731.464769437678;10181.389116653025;10332;10578.682639520826;10604.946975265075;1833.8624603154683;1928.283303224518;1937.447827369207;1942.854285928547;1951.897178094372;89245.08221703683;118122.40938795338;119319;119741.40129299385;120465.97670116459;11216.943915280372;11373.076288680391;11536.653900383002;11550.191486596026;11755.177137600285;12067.396630168456;14470.782938255934;14485.78271325931;14487.78268325976;14495.927520362331;8338.458000229966;8472.025717042496;8498.192671696179;8520.70177543783;8635.438696484709;8349.499030058216;8374.330053595697;8524.06235314115;8550.914490855012;8626.568671566396;4435.290353543418;4482.21561226784;4563.977180114078;4589.747563884019;4647.465541462708;1854.4992851929903;1953.8143876331726;1986.2352994097378;2004.959900801976;2011.6278488479677;719.7548241542162;872.9214370706667;892.5760263874607;939.3236869453982;955.4267439536361;15969.722422206196;21260.893695531424;21311.893440532698;21383.786162138178;21787.58380705249;897.6140259688397;906.6781292641017;925.6621333213463;936.8220038192721;975.4200140628;10372.769854481172;11332;11340.943295283636;11343.376114313793;11371.658850234506;571135;576288;581937;593745;593811;119897.40051299862;123169;125384;128291;129293;831.3183189784319;874.9737507874773;921.9677311294073;946.7822400847843;959.5633986536109;16886.66226675435;17846.5538361542;18439.26242950267;18738.240944289566;18815.341463048728;35311.470327945615;46089.078218436116;47803.76098119487;50319.49680503295;51550.711232219495;73191.26808731844;73662.26337736768;73948.63025684837;74865;78820.42359152899;6127.754889804536;7826.882596761167;7966.163552827102;8049.798755031171;8450.746477605682;5926.970365148147;8746.606402711766;12605.49578016869;12841.29372884463;13354.799678004643;11125.554977801121;11392.487338069972;11443.141764367872;11562.01722853567;11679.124065695276;9364.95317523408;9486.905130948602;10655.946720266658;11069.667909962714;11472.94263528677;11040.171987101161;11418.600348987746;11572.942135289606;11824.70438239051;11901.940490297493;7954.960225198837;8120.918790812017;8165.591720413837;8203;8459.365547583833;8334.87497687523;10846.566137354734;11325.15061370384;11570.363630000431;11615.24500907472 15:51:14 INFO - PROCESS | 1668 | |3;mootools.html;1530.6249968757656;1571.0416645846035;1793.0676048454804;1820.9271629134835;1893.8295553400183;7839.490433121858;8066.153053929341;8264.669413223472;8787.824243515133;8797.472151670905;8225.38309626779;8511.659533618664;8546.871796923051;8598;8643.74068777936;2284.7029886114783;2318.988405057972;2320.187934223017;2326.720793504782;2334.6964894563685;2287.165184707578;2309.3764683535437;2333.9649905251435;2337.123578658003;2338.6375111857687;2310.1336998625516;2313.8958746856347;2321.0715713714567;2329.8369114162015;2331.0559223514506;2291.40423489893;2293.896774645145;2294.5066810635794;2300.18343488061;2309.2494939144844;2299.2067736631;2299.701038864937;2303.2169062518656;2309;2310.953780924389;8547;8643.30853531716;8687.305015598737;8695.913040869573;8710.216080552716;5065.366829146356;5227.790888364499;5240.397354304266;5255.527002569749;5265.552428043621;3412.3004784019217;3472.340255351475;3607.603163651996;3610;3626.1297288650535;877.6313948141795;1023.8822535408449;1069.5775168808354;1083.4636854756945;1085.375908852405;11148.498317575728;11209.327440353605;11220.495077721522;11223.046041086518;11277.09783217349;4429.15845989261;4455.977720111444;4646.837360692427;4659.207934651117;4670.556297151765;9591.328606997582;9639.325247232626;9646.710598682052;9650.08324209199;9680.612775488902;5162.174052151638;5206.297149884793;5212.192110222922;5213;5214.765335559909;4300.913981720348;4434.26834572292;4609.861704148881;4717.292406139038;4805.447373552016;3745.700343972476;3761.3229618668356;3870.2066076454257;3888.6889048876023;3902.570717221103;1076.6716151573846;1239.20690757915;1243.055277988717;1254.3602762591145;1270.5172034626967;755.1579988312991;874.5015341255423;874.8512752832033;917.6556344954673;955.7706150523964;4818.6626936114835;5319.840404787863;5507.669539827623;5672.858178545569;5761.971190144022;364.7082334132683;395.6419440406437;395.68147641149153;399.31716764333316;417.73891317926297;3375.8480868360984;3420.794752314869;3791.4123310886857;3832.6358995895343;3872.786996715208;9415.105564971316;9957.153641940371;9968.501574921225;9998.950005250073;10056;9551.856722149034;9603.615855365708;9888.555015024342;9918.25613079022;10001.749956251006;7648.196939321291;7733.149353571215;7745.302922736981;7745.302922736981;7746.0317460317465;5416.620836541562;5459.344878614592;5471.753771080391;5509.641873278224;5555.000099982042;9033;9347.252219822534;9353.251739860658;9359.906400935903;9362.906370936476;7922.405819563441;9133.406328588886;9398.154166125081;9404.106609872051;9427.717168484956;6678.365555272241;6873.140857392826;7014.158301003912;7036.19083805369;7109;7899.486533375312;8157.021157461143;8186.918130818854;8190;8205.097439281793;6995.565908988747;7089.361957423856;7160.820979475555;7257.274272572911;7328.3404493595835;7085.858282834418;7140.214576396488;7181.425485961215;7204.0994875640545;7206.0271863297785;6644.90032649501;6753.763618273337;6786.253512113763;6805.523613347018;6812.625305608239;4830.396200474941;4833.782479788348;4862.149123903232;4900.926486102782;4941.678790878582;4508.143452743968;4656.115338085753;4736.218523943511;4784.330193772944;4907.263910413467 15:51:14 INFO - PROCESS | 1668 | |4;prototype.html;294.3127796594952;295.5168299829778;300.88867119165906;305.4761084739671;307.681549596168;4534.528681868382;4589.334546490757;4726.385569875915;5204.635675502716;5373.355197376311;5115.386153661566;5295.735213239342;5297.708626025576;5338.893222135555;5353.304070470834;3499.5275637788827;4026.597340265972;4059.3707975263733;4062.939055914164;4069.7761623110864;3941.881743547698;4031.6371526562602;4080.8367665293354;4084.97957510212;4099.713020088595;2976.345204055115;3189.8405079746035;4015.4980627421573;4029.435878976946;4044.635982761551;3319.751018673609;3502.8949131526097;3999.2201520703475;4006.1186538961383;4008.719389642726;3512.5422949475937;3519.4338519358894;3522.8766993155373;3532.964670353315;3538.43385058392;4272.44304157257;5113.974430127862;5138.53551737753;5182.4299327073995;5212.452692467275;3550.3609350316933;4602.424696912886;4627.282771170474;4663.370444989917;4673.11210869937;4462.709923854972;4886.725893651263;5293.617871957635;5590.440955904418;5630.352509461423;3474.8185616890128;3673.8530458781615;3824.29250588642;3864.3623802072625;3877.2827027000094;5095.210242412469;5802.071668533056;5817.709114544298;5841.211436456067;5860.150278209636;3457.8271086445575;3606.5311509503595;3901.3562762144497;3918.294706952719;3919.5296564411883;3768.8304026318883;4005.6595189408645;4007.318755811519;4012.6789856811383;4074.490688663917;4554.590086892195;4573.6569757268335;4588.632909367243;4590.265557510782;4609.423822022247;5549.0878930354;5618.185363122325;5618.932402843447;5633.605647604629;5668.186180422308;1259.9181053231512;1265.7531781302557;1285.8521270053877;1335.5058628307588;1342.8187194728782;753.7587971848956;919.0396036142247;937.8323986636681;940.2994768897215;940.8541676040225;4489.024829075196;5213.165893457029;5245.924585459971;5332.2801421808335;5348.358197016383;5033.823816166417;5351.351081214746;5648.802291919763;5766;5783.971080144573;4443.400140980926;4687.718736875799;5134.897302053938;5149.253358263031;5205.315813050604;2031.878087314766;2122.819560337358;2192.9473852550545;2241.843070985048;2502.624606309068;4266;4906.607471402279;5018.397792264952;5031.547160755549;5038.438084193912;4911.631627627942;5003.574696150795;5051.408806226027;5070.518300761421;5345;5347.545458636059;6052.969735151296;6053.939460605337;6078.209832721718;6132.908006379907;5078.866875912199;5480.616356855141;5752.654840709571;5781.444411686866;5970.671613061149;4974.527419895104;5625.327969214591;5707.200991861226;5721.942780572141;5906.763729450774;5900.852478688067;6137.3248942617165;6138.355472675484;6159.876802464015;6171.9691401542705;3905.0669918390577;3908.7068469864307;3952.3873799561115;3971.980140099281;3989.581093985206;5536.390996990247;5695.430456954271;5911.438413350725;5942.970285148546;5984.251968503937;4531.954680453153;4754.7147171169845;5576.442355764391;5605.747741351567;5688.288963879515;5677.716114194356;5697.658140511582;5723.227364305765;5731.8567035824435;5734.397888221677;5081.415637201769;5339.504938617193;5402.002518136629;5566.387697353369;5601.80393686219;4288.614024737789;4345.913081738411;5031.245313202902;5353.892922141613;5759.712014399347;5144.768485418091;5581.176893827741;5620.662760234399;5664.716764161858;5669.4047125051275 15:51:14 INFO - PROCESS | 1668 | |5;yui.html;632.5066448170427;723.2825749255444;736.9594672293022;752.7252552818221;809.5062012172574;13863.23752193629;16073.196340183002;16167.595810104753;16187.595310117253;16225.350985960562;14621.12273263605;15319.540413787576;15937.362505499796;16103.597410064755;16491.257893394795;837.0583094019213;855.8245559660286;860.1441565642194;863.6804382378529;864.1229152410277;545.2747845365654;692.3353580562666;839.6767244610838;863.1454859688894;867.1068799136899;891.1445012787732;907.7503686486236;907.8320510705507;908.4276905549494;909.2044461096541;893.2675206330812;903.4308385716989;904.1862323908503;905.1988989744058;906.0033962641076;640.9967950160266;642.0946465483693;655.5411212151513;657.7369052379039;661.3584822721951;16043.598910027225;16103.436379726654;16144.192790360552;16166.919165404215;16168.353265869351;12071.637850864401;12625.684357891034;14309.713805723828;14313.928430357886;14592.441175154023;11623.060662864456;11847.555923777989;11924.88075119255;12099.473935915827;12172.513099476011;3751.5873253942045;3786.962130378716;3792.0513729582885;3820.8089595520114;3821.9044523886846;120303;120867.79132208742;121394;123318;123749;10887.183461240438;10921.67234982936;11015.339079655247;11113.666590002313;11357;18245.7263141053;22143.335699929026;22274.554508909732;22275.888620557118;22608.773912260996;7871.330936870316;7981.920180798234;7984.08183058944;7998.280154786097;8147.674093036212;4446.355278484666;4489.6618319014915;4495.235809912323;4506.977465112719;4595.310703394451;3398.813065281384;3499.370113379616;3504.229069604734;3507.1407505160887;3513.9648603513638;607.1318015238252;614.1064750787593;636.9044643303541;638.5913015669975;640.1230314469207;564.5286186034707;570.7574280930588;637.7289651897925;671.1677519875417;711.4841739738648;9094.317926155565;9740;9761.658341957997;9903.207743380512;9914.058164474363;554.4704806909392;580.7618876260713;684.4457229581907;688.1845013658831;694.4652617484509;4377.489766030714;4627.838025669085;4712.882177945578;4747.477777444478;4766.261229509431;101180.49409752904;124908;152954;154292;158137;142118.57881421054;142768;142950;143952.56047439392;146434.80347794792;85700.14299856922;88721.55639221892;97354.5132274334;97431;111714.88285117208;9765.804683906425;9886.802263954536;9951.253655975686;9963.352382095141;10095.435207542843;47954.520454795005;59934.80130397455;60122.797544048;60240.39759602348;62365;71419;85920.14079859121;90738.54630726804;90863;93718.53140734253;11353;11572.073035468435;11775.941120294343;11935;12547.811782823448;11483.942580287045;12956.8056479151;14558.41766329335;14623.488177913721;15282.617934551725;13670.589882303548;13697;13899.235542045284;13934.58196254114;14411.207383594003;10866.891331086905;13107.885829704583;13498.797518037434;13521.256330901895;13938.512152074629;11752.412379380825;13558.79661805054;13655.248961307223;13671.931640341734;14365.066270692372;10208;10903.563857445613;11298.152638551977;11651.53393864236;11923.701907452036;9877.489658056274;10810.729731756768;11463.713407164554;11523.827142593036;11694.29834209916 15:51:14 INFO - PROCESS | 1668 | __end_tp_report 15:51:14 INFO - PROCESS | 1668 | __start_cc_report 15:51:14 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,363 15:51:14 INFO - PROCESS | 1668 | __end_cc_report 15:51:14 INFO - PROCESS | 1668 | __startTimestamp1475362274510__endTimestamp 15:51:14 INFO - PROCESS | 1668 | 15:51:14 INFO - PROCESS | 1668 | ------- Summary: start ------- 15:51:14 INFO - PROCESS | 1668 | Number of tests: 6 15:51:14 INFO - PROCESS | 1668 | 15:51:14 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5570.67 Median:5731.68 stddev:654.12 (11.4%) stddev-sans-first:631.42 15:51:14 INFO - PROCESS | 1668 | Values: 3300.5 3332.1 3347.3 3351.1 3367.5 5493.6 5519.2 5550.8 5562.7 5616.5 4576.9 5496.6 5557.3 5577.4 5613.4 5058.2 5111.4 5163.9 5198.7 5205.0 5024.2 5198.2 5198.2 5233.5 5238.3 4240.3 5013.5 5084.2 5189.8 5199.8 5019.7 5084.7 5119.3 5129.0 5180.8 5055.6 5057.8 5073.2 5077.8 5130.1 5453.1 5497.0 5595.7 5626.2 5641.3 5371.5 5932.9 5976.9 6136.6 6156.6 6191.1 6194.1 6197.6 6218.7 6220.2 4355.7 5006.5 5715.4 5723.5 5731.7 5022.8 5805.1 6114.2 6139.6 6176.2 4871.4 5953.4 5998.4 6003.3 6020.5 4903.8 6073.9 6092.4 6115.6 6124.5 5939.9 5997.3 6026.0 6031.8 6032.1 5980.6 6082.7 6086.1 6091.4 6115.5 4143.9 4233.5 4827.4 4829.6 4880.3 4274.9 4281.3 4314.1 4341.6 4369.5 5681.3 5683.4 5689.1 5693.7 5747.6 5000.6 6084.2 6144.4 6159.0 6163.6 5549.8 5624.4 5630.2 5631.7 5715.9 5274.2 5321.3 5333.8 5361.5 5363.7 6202.3 6212.7 6226.3 6230.8 6240.8 6018.2 6116.4 6193.1 6202.6 6216.0 6156.3 6165.2 6189.2 6214.0 6215.6 6007.9 6043.5 6043.9 6072.9 6110.0 5972.0 6146.8 6154.8 6174.7 6182.3 5725.2 5790.4 5925.1 5973.8 6089.8 5953.1 5984.2 5992.6 6000.5 6028.4 5655.7 5707.3 5743.2 5832.5 5871.6 5913.8 5934.1 5935.0 5944.8 6006.8 5713.8 5757.3 5764.1 5781.7 5788.6 15:51:14 INFO - PROCESS | 1668 | 15:51:14 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:66104.85 Median:40505.49 stddev:140092.98 (345.9%) stddev-sans-first:140399.34 15:51:14 INFO - PROCESS | 1668 | Values: 480.6 522.6 524.5 524.7 535.8 37341.4 40779.4 40806.8 40808.4 40874.0 37628.1 41493.2 41866.6 41916.8 42019.2 7904.4 8305.1 8556.7 9685.2 9772.2 9740.2 9834.8 9836.4 9844.7 9876.8 9832.0 9863.8 9872.8 9877.7 9878.6 9785.2 9833.6 9835.4 9859.4 9879.0 9639.3 9699.6 9713.5 9732.9 9741.5 39928.6 40112.6 40346.2 40396.0 40410.8 3391.0 3436.4 3530.3 3556.4 4008.7 29126.1 31081.9 31592.7 31958.0 31969.5 2993.2 3155.9 3323.7 3372.7 3372.7 426595.9 476936.0 500106.0 500748.0 505197.0 60071.0 60373.0 60480.0 60504.7 60790.4 62750.7 62866.7 62980.7 63554.7 63608.0 16179.4 16341.0 16384.5 16390.8 16417.5 12654.6 12719.5 12727.6 12741.7 12747.5 15917.2 16620.5 16634.3 16680.7 16700.7 6528.4 7130.6 7176.7 7191.8 7194.1 821.7 981.7 994.4 1016.0 1049.9 28018.7 28978.1 29545.7 29844.8 30365.8 918.9 1050.4 1057.7 1059.6 1068.4 15204.9 15363.8 15404.5 15487.2 15562.9 550359.0 588797.0 630781.8 632422.0 633698.0 55591.4 55816.7 56017.7 56087.4 56296.0 1283.6 1341.5 1350.8 1356.9 1361.5 29110.6 29304.0 29313.9 29785.7 30088.5 48246.8 48693.0 48776.0 48797.5 48801.5 471911.0 474553.0 474822.0 475804.0 477676.0 38396.8 39493.6 39604.8 39626.4 39704.0 42118.7 44456.7 44852.8 46871.0 48240.3 38228.9 39417.2 40207.2 40600.2 41017.2 28552.9 31319.7 33153.8 39734.4 40171.2 28541.6 29116.9 33355.5 33661.3 38237.4 28973.9 33582.0 34244.1 34597.8 34852.6 20084.9 25029.2 25217.9 25609.1 25733.7 15:51:14 INFO - PROCESS | 1668 | 15:51:14 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:33870.56 Median:369.20 stddev:97333.64 (26363.6%) stddev-sans-first:97574.17 15:51:14 INFO - PROCESS | 1668 | Values: 360.8 368.4 370.0 374.6 391.9 17893.7 19515.4 20180.7 20476.3 20753.6 17058.8 18891.9 20026.5 20488.7 20685.5 5766.6 5871.1 6163.4 6193.0 6198.5 6020.0 6082.5 6090.8 6138.0 6146.2 6089.9 6128.6 6137.8 6733.0 6849.9 6423.8 6767.5 6818.7 6822.0 6830.2 5917.3 5965.5 5976.5 5984.5 6002.4 13309.5 13601.9 20251.7 21773.9 25193.2 2141.6 2283.0 2434.5 2561.0 2645.9 9731.5 10181.4 10332.0 10578.7 10604.9 1833.9 1928.3 1937.4 1942.9 1951.9 89245.1 118122.4 119319.0 119741.4 120466.0 11216.9 11373.1 11536.7 11550.2 11755.2 12067.4 14470.8 14485.8 14487.8 14495.9 8338.5 8472.0 8498.2 8520.7 8635.4 8349.5 8374.3 8524.1 8550.9 8626.6 4435.3 4482.2 4564.0 4589.7 4647.5 1854.5 1953.8 1986.2 2005.0 2011.6 719.8 872.9 892.6 939.3 955.4 15969.7 21260.9 21311.9 21383.8 21787.6 897.6 906.7 925.7 936.8 975.4 10372.8 11332.0 11340.9 11343.4 11371.7 571135.0 576288.0 581937.0 593745.0 593811.0 119897.4 123169.0 125384.0 128291.0 129293.0 831.3 875.0 922.0 946.8 959.6 16886.7 17846.6 18439.3 18738.2 18815.3 35311.5 46089.1 47803.8 50319.5 51550.7 73191.3 73662.3 73948.6 74865.0 78820.4 6127.8 7826.9 7966.2 8049.8 8450.7 5927.0 8746.6 12605.5 12841.3 13354.8 11125.6 11392.5 11443.1 11562.0 11679.1 9365.0 9486.9 10655.9 11069.7 11472.9 11040.2 11418.6 11572.9 11824.7 11901.9 7955.0 8120.9 8165.6 8203.0 8459.4 8334.9 10846.6 11325.2 11570.4 11615.2 15:51:14 INFO - PROCESS | 1668 | 15:51:14 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5448.91 Median:5212.60 stddev:3023.47 (58.0%) stddev-sans-first:3017.62 15:51:14 INFO - PROCESS | 1668 | Values: 1530.6 1571.0 1793.1 1820.9 1893.8 7839.5 8066.2 8264.7 8787.8 8797.5 8225.4 8511.7 8546.9 8598.0 8643.7 2284.7 2319.0 2320.2 2326.7 2334.7 2287.2 2309.4 2334.0 2337.1 2338.6 2310.1 2313.9 2321.1 2329.8 2331.1 2291.4 2293.9 2294.5 2300.2 2309.2 2299.2 2299.7 2303.2 2309.0 2311.0 8547.0 8643.3 8687.3 8695.9 8710.2 5065.4 5227.8 5240.4 5255.5 5265.6 3412.3 3472.3 3607.6 3610.0 3626.1 877.6 1023.9 1069.6 1083.5 1085.4 11148.5 11209.3 11220.5 11223.0 11277.1 4429.2 4456.0 4646.8 4659.2 4670.6 9591.3 9639.3 9646.7 9650.1 9680.6 5162.2 5206.3 5212.2 5213.0 5214.8 4300.9 4434.3 4609.9 4717.3 4805.4 3745.7 3761.3 3870.2 3888.7 3902.6 1076.7 1239.2 1243.1 1254.4 1270.5 755.2 874.5 874.9 917.7 955.8 4818.7 5319.8 5507.7 5672.9 5762.0 364.7 395.6 395.7 399.3 417.7 3375.8 3420.8 3791.4 3832.6 3872.8 9415.1 9957.2 9968.5 9999.0 10056.0 9551.9 9603.6 9888.6 9918.3 10001.7 7648.2 7733.1 7745.3 7745.3 7746.0 5416.6 5459.3 5471.8 5509.6 5555.0 9033.0 9347.3 9353.3 9359.9 9362.9 7922.4 9133.4 9398.2 9404.1 9427.7 6678.4 6873.1 7014.2 7036.2 7109.0 7899.5 8157.0 8186.9 8190.0 8205.1 6995.6 7089.4 7160.8 7257.3 7328.3 7085.9 7140.2 7181.4 7204.1 7206.0 6644.9 6753.8 6786.3 6805.5 6812.6 4830.4 4833.8 4862.1 4900.9 4941.7 4508.1 4656.1 4736.2 4784.3 4907.3 15:51:14 INFO - PROCESS | 1668 | 15:51:14 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4474.52 Median:5032.69 stddev:1418.51 (28.2%) stddev-sans-first:1387.35 15:51:14 INFO - PROCESS | 1668 | Values: 294.3 295.5 300.9 305.5 307.7 4534.5 4589.3 4726.4 5204.6 5373.4 5115.4 5295.7 5297.7 5338.9 5353.3 3499.5 4026.6 4059.4 4062.9 4069.8 3941.9 4031.6 4080.8 4085.0 4099.7 2976.3 3189.8 4015.5 4029.4 4044.6 3319.8 3502.9 3999.2 4006.1 4008.7 3512.5 3519.4 3522.9 3533.0 3538.4 4272.4 5114.0 5138.5 5182.4 5212.5 3550.4 4602.4 4627.3 4663.4 4673.1 4462.7 4886.7 5293.6 5590.4 5630.4 3474.8 3673.9 3824.3 3864.4 3877.3 5095.2 5802.1 5817.7 5841.2 5860.2 3457.8 3606.5 3901.4 3918.3 3919.5 3768.8 4005.7 4007.3 4012.7 4074.5 4554.6 4573.7 4588.6 4590.3 4609.4 5549.1 5618.2 5618.9 5633.6 5668.2 1259.9 1265.8 1285.9 1335.5 1342.8 753.8 919.0 937.8 940.3 940.9 4489.0 5213.2 5245.9 5332.3 5348.4 5033.8 5351.4 5648.8 5766.0 5784.0 4443.4 4687.7 5134.9 5149.3 5205.3 2031.9 2122.8 2192.9 2241.8 2502.6 4266.0 4906.6 5018.4 5031.5 5038.4 4911.6 5003.6 5051.4 5070.5 5345.0 5347.5 6053.0 6053.9 6078.2 6132.9 5078.9 5480.6 5752.7 5781.4 5970.7 4974.5 5625.3 5707.2 5721.9 5906.8 5900.9 6137.3 6138.4 6159.9 6172.0 3905.1 3908.7 3952.4 3972.0 3989.6 5536.4 5695.4 5911.4 5943.0 5984.3 4532.0 4754.7 5576.4 5605.7 5688.3 5677.7 5697.7 5723.2 5731.9 5734.4 5081.4 5339.5 5402.0 5566.4 5601.8 4288.6 4345.9 5031.2 5353.9 5759.7 5144.8 5581.2 5620.7 5664.7 5669.4 15:51:14 INFO - PROCESS | 1668 | 15:51:14 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:24701.74 Median:3632.78 stddev:39748.40 (1094.2%) stddev-sans-first:39818.82 15:51:14 INFO - PROCESS | 1668 | Values: 632.5 723.3 737.0 752.7 809.5 13863.2 16073.2 16167.6 16187.6 16225.4 14621.1 15319.5 15937.4 16103.6 16491.3 837.1 855.8 860.1 863.7 864.1 545.3 692.3 839.7 863.1 867.1 891.1 907.8 907.8 908.4 909.2 893.3 903.4 904.2 905.2 906.0 641.0 642.1 655.5 657.7 661.4 16043.6 16103.4 16144.2 16166.9 16168.4 12071.6 12625.7 14309.7 14313.9 14592.4 11623.1 11847.6 11924.9 12099.5 12172.5 3751.6 3787.0 3792.1 3820.8 3821.9 120303.0 120867.8 121394.0 123318.0 123749.0 10887.2 10921.7 11015.3 11113.7 11357.0 18245.7 22143.3 22274.6 22275.9 22608.8 7871.3 7981.9 7984.1 7998.3 8147.7 4446.4 4489.7 4495.2 4507.0 4595.3 3398.8 3499.4 3504.2 3507.1 3514.0 607.1 614.1 636.9 638.6 640.1 564.5 570.8 637.7 671.2 711.5 9094.3 9740.0 9761.7 9903.2 9914.1 554.5 580.8 684.4 688.2 694.5 4377.5 4627.8 4712.9 4747.5 4766.3 101180.5 124908.0 152954.0 154292.0 158137.0 142118.6 142768.0 142950.0 143952.6 146434.8 85700.1 88721.6 97354.5 97431.0 111714.9 9765.8 9886.8 9951.3 9963.4 10095.4 47954.5 59934.8 60122.8 60240.4 62365.0 71419.0 85920.1 90738.5 90863.0 93718.5 11353.0 11572.1 11775.9 11935.0 12547.8 11483.9 12956.8 14558.4 14623.5 15282.6 13670.6 13697.0 13899.2 13934.6 14411.2 10866.9 13107.9 13498.8 13521.3 13938.5 11752.4 13558.8 13655.2 13671.9 14365.1 10208.0 10903.6 11298.2 11651.5 11923.7 9877.5 10810.7 11463.7 11523.8 11694.3 15:51:14 INFO - PROCESS | 1668 | -------- Summary: end -------- 15:51:14 INFO - PROCESS | 1668 | 15:51:15 INFO - TEST-INFO | 1668: exit 0 15:51:15 INFO - TEST-OK | dromaeo_css | took 1114760ms 15:51:15 INFO - TEST-START | kraken 15:51:15 INFO - operating with platform_type : mac_ 15:51:15 INFO - Initialising browser for kraken test... 15:51:15 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpfDjEV1/profile http://localhost:49266/getInfo.html) 15:51:17 INFO - PROCESS | 1677 | 2016-10-01 15:51:17.672 plugin-container[1678:10873] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460f, name = 'com.apple.tsm.portname' 15:51:17 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:51:17 INFO - PROCESS | 1677 | 2016-10-01 15:51:17.675 plugin-container[1678:10873] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9007, name = 'com.apple.CFPasteboardClient' 15:51:17 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:51:17 INFO - PROCESS | 1677 | 2016-10-01 15:51:17.675 plugin-container[1678:10873] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:51:17 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 15:51:17 INFO - PROCESS | 1677 | colorDepth:24 15:51:17 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 15:51:17 INFO - PROCESS | 1677 | __metrics 15:51:17 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 15:51:18 INFO - TEST-INFO | 1677: exit 0 15:51:18 INFO - Browser initialized. 15:51:18 INFO - Running cycle 1/1 for kraken test... 15:51:18 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpfDjEV1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:51:20 INFO - PROCESS | 1679 | 2016-10-01 15:51:20.867 plugin-container[1680:10987] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1f, name = 'com.apple.tsm.portname' 15:51:20 INFO - PROCESS | 1679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:51:20 INFO - PROCESS | 1679 | 2016-10-01 15:51:20.873 plugin-container[1680:10987] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 15:51:20 INFO - PROCESS | 1679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:51:20 INFO - PROCESS | 1679 | 2016-10-01 15:51:20.873 plugin-container[1680:10987] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:51:30 INFO - PROCESS | 1679 | RSS: Main: 240459776 15:51:30 INFO - PROCESS | 1679 | 15:51:33 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 15:51:34 INFO - PROCESS | 1679 | RSS: Main: 253931520 15:51:34 INFO - PROCESS | 1679 | 15:51:37 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 15:51:38 INFO - PROCESS | 1679 | RSS: Main: 261382144 15:51:38 INFO - PROCESS | 1679 | 15:51:41 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 15:51:41 INFO - PROCESS | 1679 | RSS: Main: 262217728 15:51:41 INFO - PROCESS | 1679 | 15:51:43 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 15:51:44 INFO - PROCESS | 1679 | RSS: Main: 264359936 15:51:44 INFO - PROCESS | 1679 | 15:51:46 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:51:46 INFO - PROCESS | 1679 | RSS: Main: 265060352 15:51:46 INFO - PROCESS | 1679 | 15:51:49 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 15:51:50 INFO - PROCESS | 1679 | RSS: Main: 266055680 15:51:50 INFO - PROCESS | 1679 | 15:51:53 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 15:51:54 INFO - PROCESS | 1679 | RSS: Main: 266092544 15:51:54 INFO - PROCESS | 1679 | 15:51:57 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 15:51:57 INFO - PROCESS | 1679 | RSS: Main: 266547200 15:51:57 INFO - PROCESS | 1679 | 15:51:59 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:52:00 INFO - PROCESS | 1679 | RSS: Main: 265588736 15:52:00 INFO - PROCESS | 1679 | 15:52:02 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:52:02 INFO - PROCESS | 1679 | RSS: Main: 265703424 15:52:02 INFO - PROCESS | 1679 | 15:52:04 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:52:05 INFO - PROCESS | 1679 | RSS: Main: 265756672 15:52:05 INFO - PROCESS | 1679 | 15:52:08 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:52:09 INFO - PROCESS | 1679 | RSS: Main: 266293248 15:52:09 INFO - PROCESS | 1679 | 15:52:12 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:52:12 INFO - PROCESS | 1679 | RSS: Main: 265764864 15:52:12 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 15:52:14 INFO - PROCESS | 1679 | RSS: Main: 267042816 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | __start_tp_report 15:52:14 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 15:52:14 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 15:52:14 INFO - PROCESS | 1679 | |i|pagename|runs| 15:52:14 INFO - PROCESS | 1679 | |0;ai-astar;105;99;169;173;132;121;119;119;137;175 15:52:14 INFO - PROCESS | 1679 | |1;audio-beat-detection;149;117;115;151;116;115;115;134;158;150 15:52:14 INFO - PROCESS | 1679 | |2;audio-dft;191;215;221;205;202;197;199;200;197;197 15:52:14 INFO - PROCESS | 1679 | |3;audio-fft;85;58;60;81;61;59;59;61;76;59 15:52:14 INFO - PROCESS | 1679 | |4;audio-oscillator;87;70;87;70;65;68;87;70;73;85 15:52:14 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;95;87;91;89;128;101;92;89;91;88 15:52:14 INFO - PROCESS | 1679 | |6;imaging-darkroom;166;174;133;101;101;113;148;101;101;100 15:52:14 INFO - PROCESS | 1679 | |7;imaging-desaturate;92;106;90;114;96;146;151;114;93;91 15:52:14 INFO - PROCESS | 1679 | |8;json-parse-financial;57;57;57;60;59;61;63;78;81;89 15:52:14 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;69;75;72;82;75;73;69;68;72;71 15:52:14 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;92;80;70;102;103;106;91;92;94;94 15:52:14 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;148;164;167;164;161;162;160;146;208;147 15:52:14 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;176;224;226;177;174;176;176;176;176;175 15:52:14 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;70;73;70;69;73;73;70;69;73;71 15:52:14 INFO - PROCESS | 1679 | __end_tp_report 15:52:14 INFO - PROCESS | 1679 | __start_cc_report 15:52:14 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,942 15:52:14 INFO - PROCESS | 1679 | __end_cc_report 15:52:14 INFO - PROCESS | 1679 | __startTimestamp1475362334948__endTimestamp 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | ------- Summary: start ------- 15:52:14 INFO - PROCESS | 1679 | Number of tests: 14 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:134.90 Median:153.00 stddev:28.12 (18.4%) stddev-sans-first:27.67 15:52:14 INFO - PROCESS | 1679 | Values: 105.0 99.0 169.0 173.0 132.0 121.0 119.0 119.0 137.0 175.0 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:132.00 Median:141.50 stddev:18.26 (12.9%) stddev-sans-first:18.31 15:52:14 INFO - PROCESS | 1679 | Values: 149.0 117.0 115.0 151.0 116.0 115.0 115.0 134.0 158.0 150.0 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:202.40 Median:201.00 stddev:9.11 (4.5%) stddev-sans-first:8.67 15:52:14 INFO - PROCESS | 1679 | Values: 191.0 215.0 221.0 205.0 202.0 197.0 199.0 200.0 197.0 197.0 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:65.90 Median:61.00 stddev:10.45 (17.1%) stddev-sans-first:8.50 15:52:14 INFO - PROCESS | 1679 | Values: 85.0 58.0 60.0 81.0 61.0 59.0 59.0 61.0 76.0 59.0 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:76.20 Median:79.00 stddev:9.10 (11.5%) stddev-sans-first:8.77 15:52:14 INFO - PROCESS | 1679 | Values: 87.0 70.0 87.0 70.0 65.0 68.0 87.0 70.0 73.0 85.0 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:95.10 Median:90.00 stddev:12.25 (13.6%) stddev-sans-first:12.99 15:52:14 INFO - PROCESS | 1679 | Values: 95.0 87.0 91.0 89.0 128.0 101.0 92.0 89.0 91.0 88.0 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:123.80 Median:123.00 stddev:29.31 (23.8%) stddev-sans-first:26.82 15:52:14 INFO - PROCESS | 1679 | Values: 166.0 174.0 133.0 101.0 101.0 113.0 148.0 101.0 101.0 100.0 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:109.30 Median:90.50 stddev:22.58 (25.0%) stddev-sans-first:23.07 15:52:14 INFO - PROCESS | 1679 | Values: 92.0 106.0 90.0 114.0 96.0 146.0 151.0 114.0 93.0 91.0 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:66.20 Median:62.00 stddev:11.83 (19.1%) stddev-sans-first:12.07 15:52:14 INFO - PROCESS | 1679 | Values: 57.0 57.0 57.0 60.0 59.0 61.0 63.0 78.0 81.0 89.0 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:72.60 Median:72.50 stddev:4.09 (5.6%) stddev-sans-first:4.12 15:52:14 INFO - PROCESS | 1679 | Values: 69.0 75.0 72.0 82.0 75.0 73.0 69.0 68.0 72.0 71.0 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:92.40 Median:91.50 stddev:10.81 (11.8%) stddev-sans-first:11.47 15:52:14 INFO - PROCESS | 1679 | Values: 92.0 80.0 70.0 102.0 103.0 106.0 91.0 92.0 94.0 94.0 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:162.70 Median:163.00 stddev:17.72 (10.9%) stddev-sans-first:17.98 15:52:14 INFO - PROCESS | 1679 | Values: 148.0 164.0 167.0 164.0 161.0 162.0 160.0 146.0 208.0 147.0 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.60 Median:176.00 stddev:20.79 (11.8%) stddev-sans-first:21.75 15:52:14 INFO - PROCESS | 1679 | Values: 176.0 224.0 226.0 177.0 174.0 176.0 176.0 176.0 176.0 175.0 15:52:14 INFO - PROCESS | 1679 | 15:52:14 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:72.00 stddev:1.73 (2.4%) stddev-sans-first:1.79 15:52:14 INFO - PROCESS | 1679 | Values: 70.0 73.0 70.0 69.0 73.0 73.0 70.0 69.0 73.0 71.0 15:52:14 INFO - PROCESS | 1679 | -------- Summary: end -------- 15:52:14 INFO - PROCESS | 1679 | 15:52:15 INFO - TEST-INFO | 1679: exit 0 15:52:15 INFO - TEST-OK | kraken | took 60599ms 15:52:15 INFO - SUITE-END | took 1175s 15:52:16 INFO - Completed test suite (00:19:35) 15:52:16 INFO - javascript benchmark 15:52:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7196.255637810859, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3300.471924492079, 3332.067021234055, 3347.2803347280346, 3351.0617027232374, 3367.494875768636, 5493.642913210649, 5519.2273081768535, 5550.833474995751, 5562.694051827148, 5616.494515493605, 4576.862694119173, 5496.560275177986, 5557.3331200256025, 5577.386487486373, 5613.354464236614, 5058.165402708567, 5111.437741848394, 5163.948360516403, 5198.662086964354, 5204.9739751301195, 5024.246363045536, 5198.168293073109, 5198.194279886624, 5233.502817232356, 5238.266642670029, 4240.257954857888, 5013.498650134994, 5084.186530155194, 5189.766460509249, 5199.844004679847, 5019.723915184663, 5084.694918304876, 5119.283300337956, 5129.025485157808, 5180.766865491062, 5055.646104772667, 5057.848264552069, 5073.239014147871, 5077.822276220315, 5130.07658621448, 5453.072977593819, 5497.0, 5595.7481913313995, 5626.212330273765, 5641.294838145232, 5371.543418809406, 5932.932072226998, 5976.8505787355225, 6136.6011209271255, 6156.630602163839, 6191.102290167901, 6194.070889366586, 6197.566170368031, 6218.720157592874, 6220.222472190976, 4355.693292012415, 5006.5494105530315, 5715.428457154335, 5723.484886360247, 5731.684757338345, 5022.824201152942, 5805.129230615357, 6114.2357205349335, 6139.553468857682, 6176.227971503562, 4871.3667223261455, 5953.434423729739, 5998.400159983967, 6003.339632640406, 6020.518358531307, 4903.828366007173, 6073.908891366633, 6092.360302168208, 6115.571909966348, 6124.479419249414, 5939.910901336483, 5997.340292567814, 6025.969870150621, 6031.758729650853, 6032.125341825498, 5980.55145864062, 6082.665453400017, 6086.087086936906, 6091.390860913873, 6115.510759139258, 4143.91712165755, 4233.491980962242, 4827.42070951481, 4829.5653391194965, 4880.341153944172, 4274.850380236677, 4281.336392859112, 4314.094040251583, 4341.60925516705, 4369.497507786646, 5681.318241811009, 5683.403242659544, 5689.089745640677, 5693.658380497183, 5747.597668163272, 5000.574951129122, 6084.178635884187, 6144.354842741448, 6159.0, 6163.599366041193, 5549.805756798492, 5624.353199382042, 5630.239917611152, 5631.662100273997, 5715.914261286084, 5274.182501712241, 5321.281626980308, 5333.786648534093, 5361.51746342831, 5363.678179309254, 6202.34875338092, 6212.689365531705, 6226.283977342573, 6230.7507699691505, 6240.812775616557, 6018.21763170785, 6116.35778243278, 6193.102000209854, 6202.5658203925295, 6215.96892015537, 6156.322804491592, 6165.167702360124, 6189.226346706661, 6213.96893015532, 6215.627062376272, 6007.948608993496, 6043.486303664238, 6043.909341359795, 6072.90890636632, 6110.0, 5972.014617588049, 6146.846328841814, 6154.815355539341, 6174.6912654368, 6182.319944805978, 5725.19847221381, 5790.449907258803, 5925.111233315038, 5973.790917317873, 6089.756409743561, 5953.1070339449425, 5984.162217289496, 5992.550558708202, 6000.489958353589, 6028.367021462684, 5655.660660360391, 5707.258056452634, 5743.195952566728, 5832.50423713989, 5871.588988770745, 5913.822585322448, 5934.139549765347, 5934.961381758261, 5944.791932282349, 6006.789762358297, 5713.800016999385, 5757.3379061406795, 5764.106563482667, 5781.682007489629, 5788.565857560784], "value": 5531.143360423297, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.63471761461324, 522.5714913770709, 524.4781442464748, 524.748120901967, 535.7830078818077, 37341.43987840181, 40779.38830917539, 40806.7959660202, 40808.38787418183, 40873.97815054618, 37628.05929851748, 41493.17013659725, 41866.58133418665, 41916.79041604795, 42019.15961680764, 7904.407169462284, 8305.12796156404, 8556.71649252611, 9685.176759975411, 9772.169365603932, 9740.220782337432, 9834.803303933953, 9836.3606365586, 9844.655437059704, 9876.802463950717, 9831.950840245789, 9863.753406164831, 9872.753181170492, 9877.70366888991, 9878.555465004056, 9785.217182625409, 9833.606655733762, 9835.360701554448, 9859.359141655841, 9879.012098790135, 9639.277054220962, 9699.563519641633, 9713.465759383302, 9732.90267097334, 9741.512924353825, 39928.600713993066, 40112.59887401147, 40346.19307613831, 40396.0, 40410.79794601008, 3390.9830450847835, 3436.3642726095754, 3530.2762933598806, 3556.395412779834, 4008.719389642726, 29126.126216213546, 31081.91213307546, 31592.684073159206, 31958.0, 31969.52045719316, 2993.176876358997, 3155.9347247667392, 3323.7341012718925, 3372.7301815854917, 3372.7470439717117, 426595.867020666, 476936.0, 500106.0, 500748.0, 505197.0, 60071.0, 60373.0, 60480.0, 60504.69747651234, 60790.40119985002, 62750.686246568475, 62866.68566657137, 62980.685096575144, 63554.68222658857, 63608.0, 16179.352825887068, 16341.019538827708, 16384.508464746075, 16390.754138687927, 16417.507474775775, 12654.557090501788, 12719.491220351267, 12727.554535591395, 12741.745165096832, 12747.490100396066, 15917.204139792964, 16620.501384958472, 16634.33462661504, 16680.666386672197, 16700.6659866802, 6528.37980391862, 7130.572165670076, 7176.677049532784, 7191.7842464726145, 7194.100737407824, 821.6959724901825, 981.6564202529057, 994.4480813148772, 1015.9847602285971, 1049.9317544359592, 28018.71981280161, 28978.13065608035, 29545.704542954725, 29844.80620775188, 30365.78536858501, 918.9156794981985, 1050.4170185547096, 1057.6932689519954, 1059.6397224943403, 1068.4230515521688, 15204.923975380052, 15363.769543456858, 15404.46084387041, 15487.225638718244, 15562.922185389001, 550359.0, 588797.0, 630781.8460907666, 632422.0, 633698.0, 55591.44408555863, 55816.72091639597, 56017.719911400185, 56087.43912560822, 56296.0, 1283.595667364777, 1341.4902337111835, 1350.8311461067563, 1356.938937747784, 1361.5030513862368, 29110.56334154947, 29304.0, 29313.85343073271, 29785.702142978294, 30088.54867177038, 48246.75876620735, 48693.0261394763, 48776.0, 48797.512024879295, 48801.511984881115, 471911.0, 474553.0, 474822.0, 475804.0, 477676.0, 38396.808015959745, 39493.60506394899, 39604.80197598993, 39626.40560391539, 39704.0, 42118.73643790691, 44456.66630001105, 44852.77573612111, 46871.0, 48240.276395854795, 38228.85313440601, 39417.2116557673, 40207.19585608213, 40600.1879962405, 41017.1796564073, 28552.857235713687, 31319.68680313259, 33153.83423082869, 39734.40398394084, 40171.1965760689, 28541.571876421454, 29116.854415727787, 33355.49966750549, 33661.32677346488, 38237.426438604, 28973.855130724212, 33581.99254022383, 34244.14389640279, 34597.827010864785, 34852.5978473337, 20084.89957550203, 25029.249122526355, 25217.87391063033, 25609.055533075887, 25733.742662573135], "value": 19720.95951696424, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.78713559000187, 368.4068649474346, 369.98705045323413, 374.6403452685422, 391.9039835240367, 17893.7315940261, 19515.41453756386, 20180.69728954067, 20476.283330083468, 20753.58492830146, 17058.82941170588, 18891.90554047231, 20026.49933751657, 20488.69266960997, 20685.482862928435, 5766.596338256324, 5871.119332100197, 6163.383661633846, 6193.0400787877725, 6198.535109866755, 6020.0, 6082.452579267865, 6090.786822461215, 6137.969310153443, 6146.200993870791, 6089.878202435971, 6128.6322820630685, 6137.8158655240195, 6732.98010596819, 6849.863002739942, 6423.8394040148905, 6767.458603311723, 6818.693158807866, 6821.999999999975, 6830.214525329601, 5917.260342457193, 5965.522758179335, 5976.4919981801595, 5984.461398474158, 6002.399760024006, 13309.467621295136, 13601.931990339985, 20251.696224556643, 21773.891130544245, 25193.244202673948, 2141.646628306328, 2282.988585057081, 2434.476587533671, 2561.0, 2645.915945648768, 9731.464769437678, 10181.389116653025, 10332.0, 10578.682639520826, 10604.946975265075, 1833.8624603154683, 1928.283303224518, 1937.447827369207, 1942.854285928547, 1951.897178094372, 89245.08221703683, 118122.40938795338, 119319.0, 119741.40129299385, 120465.97670116459, 11216.943915280372, 11373.076288680391, 11536.653900383002, 11550.191486596026, 11755.177137600285, 12067.396630168456, 14470.782938255934, 14485.78271325931, 14487.78268325976, 14495.927520362331, 8338.458000229966, 8472.025717042496, 8498.192671696179, 8520.70177543783, 8635.438696484709, 8349.499030058216, 8374.330053595697, 8524.06235314115, 8550.914490855012, 8626.568671566396, 4435.290353543418, 4482.21561226784, 4563.977180114078, 4589.747563884019, 4647.465541462708, 1854.4992851929903, 1953.8143876331726, 1986.2352994097378, 2004.959900801976, 2011.6278488479677, 719.7548241542162, 872.9214370706667, 892.5760263874607, 939.3236869453982, 955.4267439536361, 15969.722422206196, 21260.893695531424, 21311.893440532698, 21383.786162138178, 21787.58380705249, 897.6140259688397, 906.6781292641017, 925.6621333213463, 936.8220038192721, 975.4200140628, 10372.769854481172, 11332.0, 11340.943295283636, 11343.376114313793, 11371.658850234506, 571135.0, 576288.0, 581937.0, 593745.0, 593811.0, 119897.40051299862, 123169.0, 125384.0, 128291.0, 129293.0, 831.3183189784319, 874.9737507874773, 921.9677311294073, 946.7822400847843, 959.5633986536109, 16886.66226675435, 17846.5538361542, 18439.26242950267, 18738.240944289566, 18815.341463048728, 35311.470327945615, 46089.078218436116, 47803.76098119487, 50319.49680503295, 51550.711232219495, 73191.26808731844, 73662.26337736768, 73948.63025684837, 74865.0, 78820.42359152899, 6127.754889804536, 7826.882596761167, 7966.163552827102, 8049.798755031171, 8450.746477605682, 5926.970365148147, 8746.606402711766, 12605.49578016869, 12841.29372884463, 13354.799678004643, 11125.554977801121, 11392.487338069972, 11443.141764367872, 11562.01722853567, 11679.124065695276, 9364.95317523408, 9486.905130948602, 10655.946720266658, 11069.667909962714, 11472.94263528677, 11040.171987101161, 11418.600348987746, 11572.942135289606, 11824.70438239051, 11901.940490297493, 7954.960225198837, 8120.918790812017, 8165.591720413837, 8203.0, 8459.365547583833, 8334.87497687523, 10846.566137354734, 11325.15061370384, 11570.363630000431, 11615.24500907472], "value": 9403.232013880095, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1530.6249968757656, 1571.0416645846035, 1793.0676048454804, 1820.9271629134835, 1893.8295553400183, 7839.490433121858, 8066.153053929341, 8264.669413223472, 8787.824243515133, 8797.472151670905, 8225.38309626779, 8511.659533618664, 8546.871796923051, 8598.0, 8643.74068777936, 2284.7029886114783, 2318.988405057972, 2320.187934223017, 2326.720793504782, 2334.6964894563685, 2287.165184707578, 2309.3764683535437, 2333.9649905251435, 2337.123578658003, 2338.6375111857687, 2310.1336998625516, 2313.8958746856347, 2321.0715713714567, 2329.8369114162015, 2331.0559223514506, 2291.40423489893, 2293.896774645145, 2294.5066810635794, 2300.18343488061, 2309.2494939144844, 2299.2067736631, 2299.701038864937, 2303.2169062518656, 2309.0, 2310.953780924389, 8547.0, 8643.30853531716, 8687.305015598737, 8695.913040869573, 8710.216080552716, 5065.366829146356, 5227.790888364499, 5240.397354304266, 5255.527002569749, 5265.552428043621, 3412.3004784019217, 3472.340255351475, 3607.603163651996, 3610.0, 3626.1297288650535, 877.6313948141795, 1023.8822535408449, 1069.5775168808354, 1083.4636854756945, 1085.375908852405, 11148.498317575728, 11209.327440353605, 11220.495077721522, 11223.046041086518, 11277.09783217349, 4429.15845989261, 4455.977720111444, 4646.837360692427, 4659.207934651117, 4670.556297151765, 9591.328606997582, 9639.325247232626, 9646.710598682052, 9650.08324209199, 9680.612775488902, 5162.174052151638, 5206.297149884793, 5212.192110222922, 5213.0, 5214.765335559909, 4300.913981720348, 4434.26834572292, 4609.861704148881, 4717.292406139038, 4805.447373552016, 3745.700343972476, 3761.3229618668356, 3870.2066076454257, 3888.6889048876023, 3902.570717221103, 1076.6716151573846, 1239.20690757915, 1243.055277988717, 1254.3602762591145, 1270.5172034626967, 755.1579988312991, 874.5015341255423, 874.8512752832033, 917.6556344954673, 955.7706150523964, 4818.6626936114835, 5319.840404787863, 5507.669539827623, 5672.858178545569, 5761.971190144022, 364.7082334132683, 395.6419440406437, 395.68147641149153, 399.31716764333316, 417.73891317926297, 3375.8480868360984, 3420.794752314869, 3791.4123310886857, 3832.6358995895343, 3872.786996715208, 9415.105564971316, 9957.153641940371, 9968.501574921225, 9998.950005250073, 10056.0, 9551.856722149034, 9603.615855365708, 9888.555015024342, 9918.25613079022, 10001.749956251006, 7648.196939321291, 7733.149353571215, 7745.302922736981, 7745.302922736981, 7746.0317460317465, 5416.620836541562, 5459.344878614592, 5471.753771080391, 5509.641873278224, 5555.000099982042, 9033.0, 9347.252219822534, 9353.251739860658, 9359.906400935903, 9362.906370936476, 7922.405819563441, 9133.406328588886, 9398.154166125081, 9404.106609872051, 9427.717168484956, 6678.365555272241, 6873.140857392826, 7014.158301003912, 7036.19083805369, 7109.0, 7899.486533375312, 8157.021157461143, 8186.918130818854, 8190.0, 8205.097439281793, 6995.565908988747, 7089.361957423856, 7160.820979475555, 7257.274272572911, 7328.3404493595835, 7085.858282834418, 7140.214576396488, 7181.425485961215, 7204.0994875640545, 7206.0271863297785, 6644.90032649501, 6753.763618273337, 6786.253512113763, 6805.523613347018, 6812.625305608239, 4830.396200474941, 4833.782479788348, 4862.149123903232, 4900.926486102782, 4941.678790878582, 4508.143452743968, 4656.115338085753, 4736.218523943511, 4784.330193772944, 4907.263910413467], "value": 4323.8319774270085, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.3127796594952, 295.5168299829778, 300.88867119165906, 305.4761084739671, 307.681549596168, 4534.528681868382, 4589.334546490757, 4726.385569875915, 5204.635675502716, 5373.355197376311, 5115.386153661566, 5295.735213239342, 5297.708626025576, 5338.893222135555, 5353.304070470834, 3499.5275637788827, 4026.597340265972, 4059.3707975263733, 4062.939055914164, 4069.7761623110864, 3941.881743547698, 4031.6371526562602, 4080.8367665293354, 4084.97957510212, 4099.713020088595, 2976.345204055115, 3189.8405079746035, 4015.4980627421573, 4029.435878976946, 4044.635982761551, 3319.751018673609, 3502.8949131526097, 3999.2201520703475, 4006.1186538961383, 4008.719389642726, 3512.5422949475937, 3519.4338519358894, 3522.8766993155373, 3532.964670353315, 3538.43385058392, 4272.44304157257, 5113.974430127862, 5138.53551737753, 5182.4299327073995, 5212.452692467275, 3550.3609350316933, 4602.424696912886, 4627.282771170474, 4663.370444989917, 4673.11210869937, 4462.709923854972, 4886.725893651263, 5293.617871957635, 5590.440955904418, 5630.352509461423, 3474.8185616890128, 3673.8530458781615, 3824.29250588642, 3864.3623802072625, 3877.2827027000094, 5095.210242412469, 5802.071668533056, 5817.709114544298, 5841.211436456067, 5860.150278209636, 3457.8271086445575, 3606.5311509503595, 3901.3562762144497, 3918.294706952719, 3919.5296564411883, 3768.8304026318883, 4005.6595189408645, 4007.318755811519, 4012.6789856811383, 4074.490688663917, 4554.590086892195, 4573.6569757268335, 4588.632909367243, 4590.265557510782, 4609.423822022247, 5549.0878930354, 5618.185363122325, 5618.932402843447, 5633.605647604629, 5668.186180422308, 1259.9181053231512, 1265.7531781302557, 1285.8521270053877, 1335.5058628307588, 1342.8187194728782, 753.7587971848956, 919.0396036142247, 937.8323986636681, 940.2994768897215, 940.8541676040225, 4489.024829075196, 5213.165893457029, 5245.924585459971, 5332.2801421808335, 5348.358197016383, 5033.823816166417, 5351.351081214746, 5648.802291919763, 5766.0, 5783.971080144573, 4443.400140980926, 4687.718736875799, 5134.897302053938, 5149.253358263031, 5205.315813050604, 2031.878087314766, 2122.819560337358, 2192.9473852550545, 2241.843070985048, 2502.624606309068, 4266.0, 4906.607471402279, 5018.397792264952, 5031.547160755549, 5038.438084193912, 4911.631627627942, 5003.574696150795, 5051.408806226027, 5070.518300761421, 5345.0, 5347.545458636059, 6052.969735151296, 6053.939460605337, 6078.209832721718, 6132.908006379907, 5078.866875912199, 5480.616356855141, 5752.654840709571, 5781.444411686866, 5970.671613061149, 4974.527419895104, 5625.327969214591, 5707.200991861226, 5721.942780572141, 5906.763729450774, 5900.852478688067, 6137.3248942617165, 6138.355472675484, 6159.876802464015, 6171.9691401542705, 3905.0669918390577, 3908.7068469864307, 3952.3873799561115, 3971.980140099281, 3989.581093985206, 5536.390996990247, 5695.430456954271, 5911.438413350725, 5942.970285148546, 5984.251968503937, 4531.954680453153, 4754.7147171169845, 5576.442355764391, 5605.747741351567, 5688.288963879515, 5677.716114194356, 5697.658140511582, 5723.227364305765, 5731.8567035824435, 5734.397888221677, 5081.415637201769, 5339.504938617193, 5402.002518136629, 5566.387697353369, 5601.80393686219, 4288.614024737789, 4345.913081738411, 5031.245313202902, 5353.892922141613, 5759.712014399347, 5144.768485418091, 5581.176893827741, 5620.662760234399, 5664.716764161858, 5669.4047125051275], "value": 4022.0012373064087, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.5066448170427, 723.2825749255444, 736.9594672293022, 752.7252552818221, 809.5062012172574, 13863.23752193629, 16073.196340183002, 16167.595810104753, 16187.595310117253, 16225.350985960562, 14621.12273263605, 15319.540413787576, 15937.362505499796, 16103.597410064755, 16491.257893394795, 837.0583094019213, 855.8245559660286, 860.1441565642194, 863.6804382378529, 864.1229152410277, 545.2747845365654, 692.3353580562666, 839.6767244610838, 863.1454859688894, 867.1068799136899, 891.1445012787732, 907.7503686486236, 907.8320510705507, 908.4276905549494, 909.2044461096541, 893.2675206330812, 903.4308385716989, 904.1862323908503, 905.1988989744058, 906.0033962641076, 640.9967950160266, 642.0946465483693, 655.5411212151513, 657.7369052379039, 661.3584822721951, 16043.598910027225, 16103.436379726654, 16144.192790360552, 16166.919165404215, 16168.353265869351, 12071.637850864401, 12625.684357891034, 14309.713805723828, 14313.928430357886, 14592.441175154023, 11623.060662864456, 11847.555923777989, 11924.88075119255, 12099.473935915827, 12172.513099476011, 3751.5873253942045, 3786.962130378716, 3792.0513729582885, 3820.8089595520114, 3821.9044523886846, 120303.0, 120867.79132208742, 121394.0, 123318.0, 123749.0, 10887.183461240438, 10921.67234982936, 11015.339079655247, 11113.666590002313, 11357.0, 18245.7263141053, 22143.335699929026, 22274.554508909732, 22275.888620557118, 22608.773912260996, 7871.330936870316, 7981.920180798234, 7984.08183058944, 7998.280154786097, 8147.674093036212, 4446.355278484666, 4489.6618319014915, 4495.235809912323, 4506.977465112719, 4595.310703394451, 3398.813065281384, 3499.370113379616, 3504.229069604734, 3507.1407505160887, 3513.9648603513638, 607.1318015238252, 614.1064750787593, 636.9044643303541, 638.5913015669975, 640.1230314469207, 564.5286186034707, 570.7574280930588, 637.7289651897925, 671.1677519875417, 711.4841739738648, 9094.317926155565, 9740.0, 9761.658341957997, 9903.207743380512, 9914.058164474363, 554.4704806909392, 580.7618876260713, 684.4457229581907, 688.1845013658831, 694.4652617484509, 4377.489766030714, 4627.838025669085, 4712.882177945578, 4747.477777444478, 4766.261229509431, 101180.49409752904, 124908.0, 152954.0, 154292.0, 158137.0, 142118.57881421054, 142768.0, 142950.0, 143952.56047439392, 146434.80347794792, 85700.14299856922, 88721.55639221892, 97354.5132274334, 97431.0, 111714.88285117208, 9765.804683906425, 9886.802263954536, 9951.253655975686, 9963.352382095141, 10095.435207542843, 47954.520454795005, 59934.80130397455, 60122.797544048, 60240.39759602348, 62365.0, 71419.0, 85920.14079859121, 90738.54630726804, 90863.0, 93718.53140734253, 11353.0, 11572.073035468435, 11775.941120294343, 11935.0, 12547.811782823448, 11483.942580287045, 12956.8056479151, 14558.41766329335, 14623.488177913721, 15282.617934551725, 13670.589882303548, 13697.0, 13899.235542045284, 13934.58196254114, 14411.207383594003, 10866.891331086905, 13107.885829704583, 13498.797518037434, 13521.256330901895, 13938.512152074629, 11752.412379380825, 13558.79661805054, 13655.248961307223, 13671.931640341734, 14365.066270692372, 10208.0, 10903.563857445613, 11298.152638551977, 11651.53393864236, 11923.701907452036, 9877.489658056274, 10810.729731756768, 11463.713407164554, 11523.827142593036, 11694.29834209916], "value": 7785.016894368771, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1590.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 99.0, 169.0, 173.0, 132.0, 121.0, 119.0, 119.0, 137.0, 175.0], "value": 134.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 115.0, 151.0, 116.0, 115.0, 115.0, 134.0, 158.0, 150.0], "value": 132.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 215.0, 221.0, 205.0, 202.0, 197.0, 199.0, 200.0, 197.0, 197.0], "value": 202.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 60.0, 81.0, 61.0, 59.0, 59.0, 61.0, 76.0, 59.0], "value": 65.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 87.0, 70.0, 65.0, 68.0, 87.0, 70.0, 73.0, 85.0], "value": 76.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 87.0, 91.0, 89.0, 128.0, 101.0, 92.0, 89.0, 91.0, 88.0], "value": 95.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 174.0, 133.0, 101.0, 101.0, 113.0, 148.0, 101.0, 101.0, 100.0], "value": 123.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 106.0, 90.0, 114.0, 96.0, 146.0, 151.0, 114.0, 93.0, 91.0], "value": 109.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 60.0, 59.0, 61.0, 63.0, 78.0, 81.0, 89.0], "value": 66.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 75.0, 72.0, 82.0, 75.0, 73.0, 69.0, 68.0, 72.0, 71.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 80.0, 70.0, 102.0, 103.0, 106.0, 91.0, 92.0, 94.0, 94.0], "value": 92.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 164.0, 167.0, 164.0, 161.0, 162.0, 160.0, 146.0, 208.0, 147.0], "value": 162.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 224.0, 226.0, 177.0, 174.0, 176.0, 176.0, 176.0, 176.0, 175.0], "value": 185.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 70.0, 69.0, 73.0, 73.0, 70.0, 69.0, 73.0, 71.0], "value": 71.1, "unit": "score"}]}]} 15:52:16 INFO - Return code: 0 15:52:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:52:16 ERROR - # TBPL SUCCESS # 15:52:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:52:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:52:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:52:16 INFO - 2016-10-01 15:52:16.261 screenresolution[1684:11364] starting screenresolution argv=screenresolution get 15:52:16 INFO - 2016-10-01 15:52:16.264 screenresolution[1684:11364] Display 0: 1600x1200x32@60 15:52:16 INFO - 2016-10-01 15:52:16.270 screenresolution[1685:11368] starting screenresolution argv=screenresolution list 15:52:16 INFO - Available Modes on Display 0 15:52:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:52:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:52:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:52:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:52:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:52:16 INFO - Intel Iris: 15:52:16 INFO - Chipset Model: Intel Iris 15:52:16 INFO - Type: GPU 15:52:16 INFO - Bus: Built-In 15:52:16 INFO - VRAM (Dynamic, Max): 1536 MB 15:52:16 INFO - Vendor: Intel (0x8086) 15:52:16 INFO - Device ID: 0x0a2e 15:52:16 INFO - Revision ID: 0x0009 15:52:16 INFO - Displays: 15:52:16 INFO - Display: 15:52:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:52:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:52:16 INFO - Display Serial Number: 1600x1200 60 15:52:16 INFO - Main Display: Yes 15:52:16 INFO - Mirror: Off 15:52:16 INFO - Online: Yes 15:52:16 INFO - Rotation: Supported 15:52:16 INFO - Return code: 0 15:52:16 INFO - Running post-action listener: _resource_record_post_action 15:52:16 INFO - [mozharness: 2016-10-01 22:52:16.505867Z] Finished run-tests step (success) 15:52:16 INFO - Running post-run listener: _resource_record_post_run 15:52:16 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 33915392; Write bytes: 711148544; Read time: 319; Write time: 2114 15:52:16 INFO - TinderboxPrint: CPU usage
54.5% 15:52:16 INFO - TinderboxPrint: I/O read bytes / time
33,915,392 / 319 15:52:16 INFO - TinderboxPrint: I/O write bytes / time
711,148,544 / 2,114 15:52:16 INFO - TinderboxPrint: CPU idle
2,202.6 (45.5%) 15:52:16 INFO - TinderboxPrint: CPU system
981.7 (20.3%) 15:52:16 INFO - TinderboxPrint: CPU user
1,661.4 (34.3%) 15:52:16 INFO - TinderboxPrint: Swap in / out
438,308,864 / 0 15:52:16 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 408500736; Write bytes: 409802240; Read time: 30931; Write time: 780 15:52:16 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 29909504; Write bytes: 299949568; Read time: 288; Write time: 1328 15:52:16 INFO - Running post-run listener: _upload_blobber_files 15:52:16 INFO - Blob upload gear active. 15:52:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:52:16 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:16 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:16 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:17 INFO - (blobuploader) - INFO - Open directory for files ... 15:52:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:52:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:52:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:52:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:52:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:52:18 INFO - (blobuploader) - INFO - Done attempting. 15:52:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:52:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:52:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:52:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.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/resource-usage.json ... 15:52:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:52:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:52:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:52:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:52:21 INFO - (blobuploader) - INFO - Done attempting. 15:52:21 INFO - (blobuploader) - INFO - Iteration through files over. 15:52:21 INFO - Return code: 0 15:52:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:52:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:52:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c56a785b87a752dccbd493115961cc8e3410b33730eb8bcca9c7d40d2e2c343245b857e85708add5540f0095c197dd3cb4a0c46dd8444af1260f4d48c17dd345", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c279e09fdbe0b4df70687d9252f139f215801422f59d7cbcdfd859efcade4bf37ddff62ffa3fd0ce989c469d79a5b90c37e90659ad0c21b3784c688ebc476af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82721084261e2eece6a11b6915acea397b6aa74f1088b5d2f37a555ee2b6353d3cf3e99ff085e32f146b60c8f8eb5efc57c226e5bf321d9e9e8a86d749f2f95e"} 15:52:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:52:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:52:21 INFO - Contents: 15:52:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c56a785b87a752dccbd493115961cc8e3410b33730eb8bcca9c7d40d2e2c343245b857e85708add5540f0095c197dd3cb4a0c46dd8444af1260f4d48c17dd345", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c279e09fdbe0b4df70687d9252f139f215801422f59d7cbcdfd859efcade4bf37ddff62ffa3fd0ce989c469d79a5b90c37e90659ad0c21b3784c688ebc476af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82721084261e2eece6a11b6915acea397b6aa74f1088b5d2f37a555ee2b6353d3cf3e99ff085e32f146b60c8f8eb5efc57c226e5bf321d9e9e8a86d749f2f95e"} 15:52:21 INFO - Running post-run listener: copy_logs_to_upload_dir 15:52:21 INFO - Copying logs to upload dir... 15:52:21 INFO - mkdir: /builds/slave/test/build/upload/logs 15:52:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.506176 ========= master_lag: 1.71 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-01 15:52:23.497290) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-01 15:52:23.498491) ========= 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.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c56a785b87a752dccbd493115961cc8e3410b33730eb8bcca9c7d40d2e2c343245b857e85708add5540f0095c197dd3cb4a0c46dd8444af1260f4d48c17dd345", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c279e09fdbe0b4df70687d9252f139f215801422f59d7cbcdfd859efcade4bf37ddff62ffa3fd0ce989c469d79a5b90c37e90659ad0c21b3784c688ebc476af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82721084261e2eece6a11b6915acea397b6aa74f1088b5d2f37a555ee2b6353d3cf3e99ff085e32f146b60c8f8eb5efc57c226e5bf321d9e9e8a86d749f2f95e"} 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.011327 build_url: 'https://queue.taskcluster.net/v1/task/Nm5uGakxTeGkvxB4THZf0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c56a785b87a752dccbd493115961cc8e3410b33730eb8bcca9c7d40d2e2c343245b857e85708add5540f0095c197dd3cb4a0c46dd8444af1260f4d48c17dd345", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c279e09fdbe0b4df70687d9252f139f215801422f59d7cbcdfd859efcade4bf37ddff62ffa3fd0ce989c469d79a5b90c37e90659ad0c21b3784c688ebc476af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82721084261e2eece6a11b6915acea397b6aa74f1088b5d2f37a555ee2b6353d3cf3e99ff085e32f146b60c8f8eb5efc57c226e5bf321d9e9e8a86d749f2f95e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-01 15:52:23.538987) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-10-01 15:52:23.539333) ========= 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.rFIeMoBTjJ/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.zdLmRgxy6E/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.005070 ========= master_lag: 2.14 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-10-01 15:52:25.685312) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-01 15:52:25.685632) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-01 15:52:25.690285) ========= ========= Total master_lag: 3.95 =========