builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0205 starttime: 1482813093.52 results: success (0) buildid: 20161226193237 builduid: 236a126c81824d7781dd44c790b5f1e6 revision: 8d9fb8fa1f3db54852a586c145f2ed5afe00f27c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:33.516520) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:33.517018) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:33.517293) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:33.550857) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-12-26 20:31:33.551237) ========= 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.T20FBbMdpl/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.lIaRcGccNE/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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-12-26 20:31:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.4M=0.001s 2016-12-26 20:31:34 (18.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.490950 ========= master_lag: 3.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-12-26 20:31:37.157612) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:37.157976) ========= 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.T20FBbMdpl/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.lIaRcGccNE/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.024957 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:37.243006) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 20:31:37.245721) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8d9fb8fa1f3db54852a586c145f2ed5afe00f27c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8d9fb8fa1f3db54852a586c145f2ed5afe00f27c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.T20FBbMdpl/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.lIaRcGccNE/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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-12-26 20:31:37,354 truncating revision to first 12 chars 2016-12-26 20:31:37,354 Setting DEBUG logging. 2016-12-26 20:31:37,354 attempt 1/10 2016-12-26 20:31:37,354 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8d9fb8fa1f3d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-26 20:31:38,100 unpacking tar archive at: mozilla-aurora-8d9fb8fa1f3d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.026042 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 20:31:38.302583) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:38.302892) ========= script_repo_revision: 8d9fb8fa1f3db54852a586c145f2ed5afe00f27c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:38.303321) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:38.303733) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:38.323695) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-26 20:31:38.324078) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.T20FBbMdpl/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.lIaRcGccNE/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 20:31:38 INFO - MultiFileLogger online at 20161226 20:31:38 in /builds/slave/test 20:31:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 20:31:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:31:38 INFO - {'append_to_log': False, 20:31:38 INFO - 'base_work_dir': '/builds/slave/test', 20:31:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 20:31:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:31:38 INFO - 'branch': 'Mozilla-Aurora', 20:31:38 INFO - 'buildbot_json_path': 'buildprops.json', 20:31:38 INFO - 'config_files': ('talos/mac_config.py',), 20:31:38 INFO - 'default_actions': ('clobber', 20:31:38 INFO - 'read-buildbot-config', 20:31:38 INFO - 'download-and-extract', 20:31:38 INFO - 'populate-webroot', 20:31:38 INFO - 'create-virtualenv', 20:31:38 INFO - 'install', 20:31:38 INFO - 'run-tests'), 20:31:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:31:38 INFO - 'download_minidump_stackwalk': True, 20:31:38 INFO - 'download_symbols': 'ondemand', 20:31:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:31:38 INFO - 'tooltool.py': '/tools/tooltool.py', 20:31:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:31:38 INFO - '/tools/misc-python/virtualenv.py')}, 20:31:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:31:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:31:38 INFO - 'installer_path': 'installer.exe', 20:31:38 INFO - 'log_level': 'info', 20:31:38 INFO - 'log_name': 'talos', 20:31:38 INFO - 'log_to_console': True, 20:31:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:31:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:31:38 INFO - 'opt_config_files': (), 20:31:38 INFO - 'pip_index': False, 20:31:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:31:38 INFO - 'cmd': ('bash', 20:31:38 INFO - '-c', 20:31:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:31:38 INFO - 'enabled': True, 20:31:38 INFO - 'halt_on_failure': False, 20:31:38 INFO - 'name': 'check_screen_resolution'},), 20:31:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:31:38 INFO - 'cmd': ('bash', 20:31:38 INFO - '-c', 20:31:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:31:38 INFO - 'enabled': True, 20:31:38 INFO - 'halt_on_failure': False, 20:31:38 INFO - 'name': 'check_screen_resolution'},), 20:31:38 INFO - 'run_cmd_checks_enabled': True, 20:31:38 INFO - 'sps_profile': False, 20:31:38 INFO - 'sps_profile_interval': 0, 20:31:38 INFO - 'suite': 'dromaeojs-e10s', 20:31:38 INFO - 'system_bits': '32', 20:31:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:31:38 INFO - 'title': 't-yosemite-r7-0205', 20:31:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:31:38 INFO - 'use_talos_json': True, 20:31:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:31:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:31:38 INFO - 'work_dir': 'build'} 20:31:38 INFO - [mozharness: 2016-12-27 04:31:38.531399Z] Running clobber step. 20:31:38 INFO - Running pre-action listener: _resource_record_pre_action 20:31:38 INFO - Running main action method: clobber 20:31:38 INFO - rmtree: /builds/slave/test/build 20:31:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:31:40 INFO - Running post-action listener: _resource_record_post_action 20:31:40 INFO - [mozharness: 2016-12-27 04:31:40.966898Z] Finished clobber step (success) 20:31:40 INFO - [mozharness: 2016-12-27 04:31:40.967064Z] Running read-buildbot-config step. 20:31:40 INFO - Running pre-action listener: _resource_record_pre_action 20:31:40 INFO - Running main action method: read_buildbot_config 20:31:40 INFO - Using buildbot properties: 20:31:40 INFO - { 20:31:40 INFO - "project": "", 20:31:40 INFO - "product": "firefox", 20:31:40 INFO - "script_repo_revision": "production", 20:31:40 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 20:31:40 INFO - "repository": "", 20:31:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 20:31:40 INFO - "buildid": "20161226193237", 20:31:40 INFO - "pgo_build": "False", 20:31:40 INFO - "basedir": "/builds/slave/test", 20:31:40 INFO - "buildnumber": 52, 20:31:40 INFO - "slavename": "t-yosemite-r7-0205", 20:31:40 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 20:31:40 INFO - "platform": "yosemite_r7", 20:31:40 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 20:31:40 INFO - "branch": "mozilla-aurora", 20:31:40 INFO - "repo_path": "releases/mozilla-aurora", 20:31:40 INFO - "revision": "8d9fb8fa1f3db54852a586c145f2ed5afe00f27c", 20:31:40 INFO - "stage_platform": "macosx64", 20:31:40 INFO - "builduid": "236a126c81824d7781dd44c790b5f1e6", 20:31:40 INFO - "slavebuilddir": "test" 20:31:40 INFO - } 20:31:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 20:31:40 INFO - Running post-action listener: _resource_record_post_action 20:31:40 INFO - [mozharness: 2016-12-27 04:31:40.970436Z] Finished read-buildbot-config step (success) 20:31:40 INFO - [mozharness: 2016-12-27 04:31:40.970571Z] Running download-and-extract step. 20:31:40 INFO - Running pre-action listener: _resource_record_pre_action 20:31:40 INFO - Running main action method: download_and_extract 20:31:40 INFO - mkdir: /builds/slave/test/build/tests 20:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:31:40 INFO - trying https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 20:31:40 INFO - Downloading https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 20:31:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 20:31:42 INFO - Downloaded 1199 bytes. 20:31:42 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 20:31:42 INFO - Using the following test package requirements: 20:31:42 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 20:31:42 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:42 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 20:31:42 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:42 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 20:31:42 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:42 INFO - u'jsshell-mac.zip'], 20:31:42 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:42 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 20:31:42 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 20:31:42 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:42 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 20:31:42 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:42 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 20:31:42 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:42 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 20:31:42 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:42 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 20:31:42 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 20:31:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 20:31:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:31:42 INFO - Fetch https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 20:31:42 INFO - Http code: 200 20:31:42 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 20:31:42 INFO - x-amz-cf-id: rHctDiO8HJptP0Jnwn6fzznyB-UKb4k8lo_wQjq8EjJqscpCmxINJA== 20:31:42 INFO - x-amz-version-id: yF6gys6MsIfR0K.ItVjHHtGb.1sOLXFR 20:31:42 INFO - Expected file size: 16268334 20:31:42 INFO - Obtained file size: 16268334 20:31:49 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 20:31:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 20:31:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:31:49 INFO - Fetch https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 20:31:50 INFO - Http code: 200 20:31:50 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 20:31:50 INFO - x-amz-cf-id: YkTfVlJxXol3jYkmP7f3X5uDBSKh4rzPHrjGvK1mM5rTAeY0g7OrOg== 20:31:50 INFO - x-amz-version-id: yF6gys6MsIfR0K.ItVjHHtGb.1sOLXFR 20:31:50 INFO - Expected file size: 16268334 20:31:50 INFO - Obtained file size: 16268334 20:31:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 20:31:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 20:31:57 INFO - Fetch https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 20:31:58 INFO - Http code: 200 20:31:58 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 20:31:58 INFO - x-amz-cf-id: kKsxvxsqJ_L7SJbD5WKwbJvtQgP4fMaMQyJEqOReD69mKhKQHqXH0g== 20:31:58 INFO - x-amz-version-id: 1LZiSNBSTtNb0pB.nZp1jHoAyYaIFq18 20:31:58 INFO - Expected file size: 14409038 20:31:58 INFO - Obtained file size: 14409038 20: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')]} 20:31:58 INFO - trying https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:31:58 INFO - Downloading https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 20:31:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 20:32:01 INFO - Downloaded 109821250 bytes. 20:32:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:32:01 INFO - mkdir: /builds/slave/test/properties 20:32:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:32:01 INFO - Writing to file /builds/slave/test/properties/build_url 20:32:01 INFO - Contents: 20:32:01 INFO - build_url:https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:32:02 INFO - Running post-action listener: _resource_record_post_action 20:32:02 INFO - Running post-action listener: set_extra_try_arguments 20:32:02 INFO - [mozharness: 2016-12-27 04:32:02.086995Z] Finished download-and-extract step (success) 20:32:02 INFO - [mozharness: 2016-12-27 04:32:02.087140Z] Running populate-webroot step. 20:32:02 INFO - Running pre-action listener: _resource_record_pre_action 20:32:02 INFO - Running main action method: populate_webroot 20:32:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:32:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:32:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:32:02 INFO - '--tppagecycles', 20:32:02 INFO - '7'], 20:32:02 INFO - 'tests': ['tsvgm']}}, 20:32:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:32:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:32:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:32:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:32:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:32:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:32:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:02 INFO - 'talos_options': ['--disable-e10s'], 20:32:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:32:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:32:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:02 INFO - 'talos_options': ['--disable-e10s'], 20:32:02 INFO - 'tests': ['damp', 'tps']}, 20:32:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:02 INFO - 'tests': ['damp', 'tps']}, 20:32:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:32:02 INFO - 'tests': ['dromaeo_dom']}, 20:32:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:32:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:32:02 INFO - 'tests': ['basic_compositor_video']}, 20:32:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:32:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:32:02 INFO - 'tests': ['a11yr', 20:32:02 INFO - 'ts_paint', 20:32:02 INFO - 'tpaint', 20:32:02 INFO - 'sessionrestore', 20:32:02 INFO - 'sessionrestore_no_auto_restore', 20:32:02 INFO - 'tabpaint']}, 20:32:02 INFO - 'other-e10s': {'tests': ['a11yr', 20:32:02 INFO - 'ts_paint', 20:32:02 INFO - 'tpaint', 20:32:02 INFO - 'sessionrestore', 20:32:02 INFO - 'sessionrestore_no_auto_restore', 20:32:02 INFO - 'tabpaint']}, 20:32:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:32:02 INFO - 'ts_paint', 20:32:02 INFO - 'tpaint', 20:32:02 INFO - 'sessionrestore', 20:32:02 INFO - 'sessionrestore_no_auto_restore', 20:32:02 INFO - 'tabpaint']}, 20:32:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:32:02 INFO - 'ts_paint', 20:32:02 INFO - 'tpaint', 20:32:02 INFO - 'sessionrestore', 20:32:02 INFO - 'sessionrestore_no_auto_restore', 20:32:02 INFO - 'tabpaint']}, 20:32:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:32:02 INFO - 'tests': ['a11yr', 20:32:02 INFO - 'ts_paint', 20:32:02 INFO - 'tpaint', 20:32:02 INFO - 'sessionrestore', 20:32:02 INFO - 'sessionrestore_no_auto_restore', 20:32:02 INFO - 'tabpaint']}, 20:32:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:32:02 INFO - 'tests': ['a11yr', 20:32:02 INFO - 'ts_paint', 20:32:02 INFO - 'tpaint', 20:32:02 INFO - 'sessionrestore', 20:32:02 INFO - 'sessionrestore_no_auto_restore', 20:32:02 INFO - 'tabpaint']}, 20:32:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:32:02 INFO - 'tests': ['tsvgx', 20:32:02 INFO - 'tsvgr_opacity', 20:32:02 INFO - 'tart', 20:32:02 INFO - 'tscrollx', 20:32:02 INFO - 'cart']}, 20:32:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:32:02 INFO - 'tsvgr_opacity', 20:32:02 INFO - 'tart', 20:32:02 INFO - 'tscrollx', 20:32:02 INFO - 'cart']}, 20:32:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:02 INFO - 'talos_options': ['--disable-e10s'], 20:32:02 INFO - 'tests': ['tp5o']}, 20:32:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:02 INFO - 'tests': ['tp5o']}, 20:32:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:02 INFO - 'talos_options': ['--disable-e10s', 20:32:02 INFO - '--xperf_path', 20:32:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:32:02 INFO - 'tests': ['tp5n']}, 20:32:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:02 INFO - 'talos_options': ['--xperf_path', 20:32:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:32:02 INFO - 'tests': ['tp5n']}}, 20:32:02 INFO - 'talos.zip': {'path': '', 20:32:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:32:02 INFO - Running post-action listener: _resource_record_post_action 20:32:02 INFO - [mozharness: 2016-12-27 04:32:02.106762Z] Finished populate-webroot step (success) 20:32:02 INFO - [mozharness: 2016-12-27 04:32:02.106871Z] Running create-virtualenv step. 20:32:02 INFO - Running pre-action listener: _resource_record_pre_action 20:32:02 INFO - Running main action method: create_virtualenv 20:32:02 INFO - Creating virtualenv /builds/slave/test/build/venv 20:32:02 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:32:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:32:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:32:02 INFO - Using real prefix '/tools/python27' 20:32:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:32:02 INFO - Installing distribute.............................................................................................................................................................................................done. 20:32:05 INFO - Installing pip.................done. 20:32:05 INFO - Return code: 0 20:32:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:32:06 INFO - Reading from file tmpfile_stdout 20:32:06 INFO - Output received: 20:32:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:32:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:06 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 0x108ff0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f66570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff940557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e04d30>, '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 0x108c6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dfcb20>, '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.T20FBbMdpl/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.lIaRcGccNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T20FBbMdpl/Render', 20:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:06 INFO - 'HOME': '/Users/cltbld', 20:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:06 INFO - 'LOGNAME': 'cltbld', 20:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:06 INFO - 'MOZ_NO_REMOTE': '1', 20:32:06 INFO - 'NO_EM_RESTART': '1', 20:32:06 INFO - 'PAGER': '/bin/cat', 20:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:06 INFO - 'PWD': '/builds/slave/test', 20:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:06 INFO - 'SHELL': '/bin/bash', 20:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lIaRcGccNE/Listeners', 20:32:06 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 20:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:06 INFO - 'USER': 'cltbld', 20:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:06 INFO - 'XPC_FLAGS': '0x0', 20:32:06 INFO - 'XPC_SERVICE_NAME': '0', 20:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:32:06 INFO - Cleaning up... 20:32:06 INFO - Return code: 0 20:32:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:06 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 0x108ff0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f66570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff940557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e04d30>, '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 0x108c6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dfcb20>, '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.T20FBbMdpl/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.lIaRcGccNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T20FBbMdpl/Render', 20:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:06 INFO - 'HOME': '/Users/cltbld', 20:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:06 INFO - 'LOGNAME': 'cltbld', 20:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:06 INFO - 'MOZ_NO_REMOTE': '1', 20:32:06 INFO - 'NO_EM_RESTART': '1', 20:32:06 INFO - 'PAGER': '/bin/cat', 20:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:06 INFO - 'PWD': '/builds/slave/test', 20:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:06 INFO - 'SHELL': '/bin/bash', 20:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lIaRcGccNE/Listeners', 20:32:06 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 20:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:06 INFO - 'USER': 'cltbld', 20:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:06 INFO - 'XPC_FLAGS': '0x0', 20:32:06 INFO - 'XPC_SERVICE_NAME': '0', 20:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:06 INFO - Downloading/unpacking psutil>=3.1.1 20:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:32:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:32:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:32:10 INFO - Installing collected packages: psutil 20:32:10 INFO - Running setup.py install for psutil 20:32:10 INFO - building 'psutil._psutil_osx' extension 20:32:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:32:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 20:32:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 20:32:11 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 20:32:11 INFO - building 'psutil._psutil_posix' extension 20:32:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:32:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:32:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:32:11 INFO - ^ 20:32:11 INFO - 1 warning generated. 20:32:11 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:32:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:32:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:32:11 INFO - Successfully installed psutil 20:32:11 INFO - Cleaning up... 20:32:11 INFO - Return code: 0 20:32:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:32: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')]} 20:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32: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')]} 20:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108ff0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f66570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff940557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e04d30>, '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 0x108c6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dfcb20>, '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.T20FBbMdpl/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.lIaRcGccNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:32:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T20FBbMdpl/Render', 20:32:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:11 INFO - 'HOME': '/Users/cltbld', 20:32:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:11 INFO - 'LOGNAME': 'cltbld', 20:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:11 INFO - 'MOZ_NO_REMOTE': '1', 20:32:11 INFO - 'NO_EM_RESTART': '1', 20:32:11 INFO - 'PAGER': '/bin/cat', 20:32:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:11 INFO - 'PWD': '/builds/slave/test', 20:32:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:11 INFO - 'SHELL': '/bin/bash', 20:32:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lIaRcGccNE/Listeners', 20:32:11 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 20:32:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:11 INFO - 'USER': 'cltbld', 20:32:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:11 INFO - 'XPC_FLAGS': '0x0', 20:32:11 INFO - 'XPC_SERVICE_NAME': '0', 20:32:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:32:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:32:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:32:14 INFO - Installing collected packages: mozsystemmonitor 20:32:14 INFO - Running setup.py install for mozsystemmonitor 20:32:14 INFO - Successfully installed mozsystemmonitor 20:32:14 INFO - Cleaning up... 20:32:14 INFO - Return code: 0 20:32:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:14 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 0x108ff0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f66570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff940557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e04d30>, '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 0x108c6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dfcb20>, '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.T20FBbMdpl/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.lIaRcGccNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:32:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:32:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T20FBbMdpl/Render', 20:32:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:14 INFO - 'HOME': '/Users/cltbld', 20:32:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:14 INFO - 'LOGNAME': 'cltbld', 20:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:14 INFO - 'MOZ_NO_REMOTE': '1', 20:32:14 INFO - 'NO_EM_RESTART': '1', 20:32:14 INFO - 'PAGER': '/bin/cat', 20:32:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:14 INFO - 'PWD': '/builds/slave/test', 20:32:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:14 INFO - 'SHELL': '/bin/bash', 20:32:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lIaRcGccNE/Listeners', 20:32:14 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 20:32:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:14 INFO - 'USER': 'cltbld', 20:32:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:14 INFO - 'XPC_FLAGS': '0x0', 20:32:14 INFO - 'XPC_SERVICE_NAME': '0', 20:32:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:15 INFO - Downloading/unpacking jsonschema==2.5.1 20:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:32:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:18 INFO - Downloading functools32-3.2.3-2.tar.gz 20:32:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:32:18 INFO - warning: no files found matching '*.txt' 20:32:18 INFO - no previously-included directories found matching 'build' 20:32:18 INFO - no previously-included directories found matching 'dist' 20:32:18 INFO - no previously-included directories found matching '.git*' 20:32:18 INFO - Installing collected packages: jsonschema, functools32 20:32:18 INFO - Running setup.py install for functools32 20:32:18 INFO - warning: no files found matching '*.txt' 20:32:18 INFO - no previously-included directories found matching 'build' 20:32:18 INFO - no previously-included directories found matching 'dist' 20:32:18 INFO - no previously-included directories found matching '.git*' 20:32:18 INFO - Successfully installed jsonschema functools32 20:32:18 INFO - Cleaning up... 20:32:18 INFO - Return code: 0 20:32:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:32:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:18 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 0x108ff0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f66570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff940557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e04d30>, '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 0x108c6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dfcb20>, '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.T20FBbMdpl/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.lIaRcGccNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:32:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:32:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T20FBbMdpl/Render', 20:32:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:18 INFO - 'HOME': '/Users/cltbld', 20:32:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:18 INFO - 'LOGNAME': 'cltbld', 20:32:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:18 INFO - 'MOZ_NO_REMOTE': '1', 20:32:18 INFO - 'NO_EM_RESTART': '1', 20:32:18 INFO - 'PAGER': '/bin/cat', 20:32:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:18 INFO - 'PWD': '/builds/slave/test', 20:32:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:18 INFO - 'SHELL': '/bin/bash', 20:32:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lIaRcGccNE/Listeners', 20:32:18 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 20:32:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:18 INFO - 'USER': 'cltbld', 20:32:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:18 INFO - 'XPC_FLAGS': '0x0', 20:32:18 INFO - 'XPC_SERVICE_NAME': '0', 20:32:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:32:18 INFO - Cleaning up... 20:32:18 INFO - Return code: 0 20:32:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:32:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:18 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 0x108ff0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f66570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff940557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e04d30>, '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 0x108c6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dfcb20>, '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.T20FBbMdpl/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.lIaRcGccNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:32:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:32:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T20FBbMdpl/Render', 20:32:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:18 INFO - 'HOME': '/Users/cltbld', 20:32:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:18 INFO - 'LOGNAME': 'cltbld', 20:32:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:18 INFO - 'MOZ_NO_REMOTE': '1', 20:32:18 INFO - 'NO_EM_RESTART': '1', 20:32:18 INFO - 'PAGER': '/bin/cat', 20:32:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:18 INFO - 'PWD': '/builds/slave/test', 20:32:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:18 INFO - 'SHELL': '/bin/bash', 20:32:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lIaRcGccNE/Listeners', 20:32:18 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 20:32:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:18 INFO - 'USER': 'cltbld', 20:32:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:18 INFO - 'XPC_FLAGS': '0x0', 20:32:18 INFO - 'XPC_SERVICE_NAME': '0', 20:32:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:19 INFO - Downloading/unpacking blobuploader==1.2.4 20:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:21 INFO - Downloading blobuploader-1.2.4.tar.gz 20:32:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:32:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:32:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:22 INFO - Downloading docopt-0.6.1.tar.gz 20:32:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:32:23 INFO - Installing collected packages: blobuploader, requests, docopt 20:32:23 INFO - Running setup.py install for blobuploader 20:32:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:32:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:32:23 INFO - Running setup.py install for requests 20:32:23 INFO - Running setup.py install for docopt 20:32:23 INFO - Successfully installed blobuploader requests docopt 20:32:23 INFO - Cleaning up... 20:32:23 INFO - Return code: 0 20:32:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:32:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:23 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 0x108ff0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f66570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff940557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e04d30>, '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 0x108c6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dfcb20>, '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.T20FBbMdpl/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.lIaRcGccNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:32:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:32:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T20FBbMdpl/Render', 20:32:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:23 INFO - 'HOME': '/Users/cltbld', 20:32:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:23 INFO - 'LOGNAME': 'cltbld', 20:32:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:23 INFO - 'MOZ_NO_REMOTE': '1', 20:32:23 INFO - 'NO_EM_RESTART': '1', 20:32:23 INFO - 'PAGER': '/bin/cat', 20:32:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:23 INFO - 'PWD': '/builds/slave/test', 20:32:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:23 INFO - 'SHELL': '/bin/bash', 20:32:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lIaRcGccNE/Listeners', 20:32:23 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 20:32:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:23 INFO - 'USER': 'cltbld', 20:32:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:23 INFO - 'XPC_FLAGS': '0x0', 20:32:23 INFO - 'XPC_SERVICE_NAME': '0', 20:32:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:32:24 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-KljMCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:32:24 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-oDsIuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:32:24 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-AvgSfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:32:24 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-N7SjkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:32:24 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-V9d4GK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:32:24 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-qXWyYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:32:24 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-q_o9pt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:32:24 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-fzYX32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:32:25 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-34B76g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:32:25 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-EG4pB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:32:25 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-q_pFQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:32:25 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-6ZJbK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:32:25 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-UzIWYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:32:25 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-GLDn9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:32:25 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-u7480Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:32:25 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-avkDlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:32:25 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-lFRJiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:32:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:32:25 INFO - Running setup.py install for manifestparser 20:32:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:32:26 INFO - Running setup.py install for mozcrash 20:32:26 INFO - Running setup.py install for mozdebug 20:32:26 INFO - Running setup.py install for mozdevice 20:32:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:32:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:32:26 INFO - Running setup.py install for mozfile 20:32:26 INFO - Running setup.py install for mozhttpd 20:32:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:32:26 INFO - Running setup.py install for mozinfo 20:32:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:32:27 INFO - Running setup.py install for mozInstall 20:32:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:32:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:32:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:32:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:32:27 INFO - Running setup.py install for mozleak 20:32:27 INFO - Running setup.py install for mozlog 20:32:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:32:27 INFO - Running setup.py install for moznetwork 20:32:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:32:27 INFO - Running setup.py install for mozprocess 20:32:27 INFO - Running setup.py install for mozprofile 20:32:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:32:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:32:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:32:27 INFO - Running setup.py install for mozrunner 20:32:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:32:28 INFO - Running setup.py install for mozscreenshot 20:32:28 INFO - Running setup.py install for moztest 20:32:28 INFO - Running setup.py install for mozversion 20:32:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:32:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:32:28 INFO - Cleaning up... 20:32:28 INFO - Return code: 0 20:32:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:28 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 0x108ff0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f66570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff940557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e04d30>, '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 0x108c6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dfcb20>, '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.T20FBbMdpl/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.lIaRcGccNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:32:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:32:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T20FBbMdpl/Render', 20:32:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:28 INFO - 'HOME': '/Users/cltbld', 20:32:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:28 INFO - 'LOGNAME': 'cltbld', 20:32:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:28 INFO - 'MOZ_NO_REMOTE': '1', 20:32:28 INFO - 'NO_EM_RESTART': '1', 20:32:28 INFO - 'PAGER': '/bin/cat', 20:32:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:28 INFO - 'PWD': '/builds/slave/test', 20:32:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:28 INFO - 'SHELL': '/bin/bash', 20:32:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lIaRcGccNE/Listeners', 20:32:28 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 20:32:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:28 INFO - 'USER': 'cltbld', 20:32:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:28 INFO - 'XPC_FLAGS': '0x0', 20:32:28 INFO - 'XPC_SERVICE_NAME': '0', 20:32:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:32:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-gmKwlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:32:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-gdBScJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:32:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-q5tBFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:32:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Wqkk3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:32:29 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)) 20:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:32:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-PHLkPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:32:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-bltt3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:32:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-FEAbs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:32:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-7DLTtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:32:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-1Fcatg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:32:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-F_HHQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:32:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-nNxKRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:32:30 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-SRMTUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:32:30 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)) 20:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:32:30 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-f19Xyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:32:30 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-kMujOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:32:30 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-6tqliU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:32:30 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-xloJE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:32:30 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)) 20:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:32:30 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-HesAQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:32:30 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)) 20:32:30 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)) 20:32:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:33 INFO - Downloading blessings-1.6.tar.gz 20:32:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:32:33 INFO - Installing collected packages: blessings 20:32:33 INFO - Running setup.py install for blessings 20:32:33 INFO - Successfully installed blessings 20:32:33 INFO - Cleaning up... 20:32:33 INFO - Return code: 0 20:32:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:32:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:32:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:32:33 INFO - Reading from file tmpfile_stdout 20:32:33 INFO - Current package versions: 20:32:33 INFO - blessings == 1.6 20:32:33 INFO - blobuploader == 1.2.4 20:32:33 INFO - docopt == 0.6.1 20:32:33 INFO - functools32 == 3.2.3-2 20:32:33 INFO - jsonschema == 2.5.1 20:32:33 INFO - manifestparser == 1.1 20:32:33 INFO - mozInstall == 1.12 20:32:33 INFO - mozcrash == 1.0 20:32:33 INFO - mozdebug == 0.1 20:32:33 INFO - mozdevice == 0.48 20:32:33 INFO - mozfile == 1.2 20:32:33 INFO - mozhttpd == 0.7 20:32:33 INFO - mozinfo == 0.9 20:32:34 INFO - mozleak == 0.1 20:32:34 INFO - mozlog == 3.4 20:32:34 INFO - moznetwork == 0.27 20:32:34 INFO - mozprocess == 0.23 20:32:34 INFO - mozprofile == 0.28 20:32:34 INFO - mozrunner == 6.13 20:32:34 INFO - mozscreenshot == 0.1 20:32:34 INFO - mozsystemmonitor == 0.3 20:32:34 INFO - moztest == 0.7 20:32:34 INFO - mozversion == 1.4 20:32:34 INFO - psutil == 3.1.1 20:32:34 INFO - requests == 1.2.3 20:32:34 INFO - wsgiref == 0.1.2 20:32:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:34 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 0x108ff0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f66570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff940557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e04d30>, '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 0x108c6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dfcb20>, '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.T20FBbMdpl/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.lIaRcGccNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:32:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T20FBbMdpl/Render', 20:32:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:34 INFO - 'HOME': '/Users/cltbld', 20:32:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:34 INFO - 'LOGNAME': 'cltbld', 20:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:34 INFO - 'MOZ_NO_REMOTE': '1', 20:32:34 INFO - 'NO_EM_RESTART': '1', 20:32:34 INFO - 'PAGER': '/bin/cat', 20:32:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:34 INFO - 'PWD': '/builds/slave/test', 20:32:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:34 INFO - 'SHELL': '/bin/bash', 20:32:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lIaRcGccNE/Listeners', 20:32:34 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 20:32:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:34 INFO - 'USER': 'cltbld', 20:32:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:34 INFO - 'XPC_FLAGS': '0x0', 20:32:34 INFO - 'XPC_SERVICE_NAME': '0', 20:32:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:32:34 INFO - Cleaning up... 20:32:34 INFO - Return code: 0 20:32:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:34 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 0x108ff0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f66570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff940557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e04d30>, '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 0x108c6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dfcb20>, '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.T20FBbMdpl/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.lIaRcGccNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:32:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T20FBbMdpl/Render', 20:32:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:34 INFO - 'HOME': '/Users/cltbld', 20:32:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:34 INFO - 'LOGNAME': 'cltbld', 20:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:34 INFO - 'MOZ_NO_REMOTE': '1', 20:32:34 INFO - 'NO_EM_RESTART': '1', 20:32:34 INFO - 'PAGER': '/bin/cat', 20:32:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:34 INFO - 'PWD': '/builds/slave/test', 20:32:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:34 INFO - 'SHELL': '/bin/bash', 20:32:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lIaRcGccNE/Listeners', 20:32:34 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 20:32:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:34 INFO - 'USER': 'cltbld', 20:32:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:34 INFO - 'XPC_FLAGS': '0x0', 20:32:34 INFO - 'XPC_SERVICE_NAME': '0', 20:32:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:32:34 INFO - Cleaning up... 20:32:34 INFO - Return code: 0 20:32:34 INFO - Running post-action listener: _resource_record_post_action 20:32:34 INFO - Running post-action listener: _start_resource_monitoring 20:32:34 INFO - Starting resource monitoring. 20:32:34 INFO - [mozharness: 2016-12-27 04:32:34.579840Z] Finished create-virtualenv step (success) 20:32:34 INFO - [mozharness: 2016-12-27 04:32:34.580906Z] Running install step. 20:32:34 INFO - Running pre-action listener: _resource_record_pre_action 20:32:34 INFO - Running main action method: install 20:32:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:32:34 INFO - Reading from file tmpfile_stdout 20:32:34 INFO - Detecting whether we're running mozinstall >=1.0... 20:32:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:32:34 INFO - Reading from file tmpfile_stdout 20:32:34 INFO - Output received: 20:32:34 INFO - Usage: mozinstall [options] installer 20:32:34 INFO - Options: 20:32:34 INFO - -h, --help show this help message and exit 20:32:34 INFO - -d DEST, --destination=DEST 20:32:34 INFO - Directory to install application into. [default: 20:32:34 INFO - "/builds/slave/test"] 20:32:34 INFO - --app=APP Application being installed. [default: firefox] 20:32:34 INFO - mkdir: /builds/slave/test/build/application 20:32:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 20:33:06 INFO - Reading from file tmpfile_stdout 20:33:06 INFO - Output received: 20:33:06 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 20:33:06 INFO - Running post-action listener: _resource_record_post_action 20:33:06 INFO - [mozharness: 2016-12-27 04:33:06.121042Z] Finished install step (success) 20:33:06 INFO - [mozharness: 2016-12-27 04:33:06.121181Z] Running run-tests step. 20:33:06 INFO - Running pre-action listener: _resource_record_pre_action 20:33:06 INFO - Running main action method: run_tests 20:33:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:33:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:33:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:33:06 INFO - 2016-12-26 20:33:06.135 screenresolution[1638:8173] starting screenresolution argv=screenresolution get 20:33:06 INFO - 2016-12-26 20:33:06.138 screenresolution[1638:8173] Display 0: 1600x1200x32@60 20:33:06 INFO - 2016-12-26 20:33:06.144 screenresolution[1639:8175] starting screenresolution argv=screenresolution list 20:33:06 INFO - Available Modes on Display 0 20:33:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:33:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:33:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:33:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:33:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:33:06 INFO - Intel Iris: 20:33:06 INFO - Chipset Model: Intel Iris 20:33:06 INFO - Type: GPU 20:33:06 INFO - Bus: Built-In 20:33:06 INFO - VRAM (Dynamic, Max): 1536 MB 20:33:06 INFO - Vendor: Intel (0x8086) 20:33:06 INFO - Device ID: 0x0a2e 20:33:06 INFO - Revision ID: 0x0009 20:33:06 INFO - Displays: 20:33:06 INFO - Display: 20:33:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:33:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:33:06 INFO - Display Serial Number: 1600x1200 60 20:33:06 INFO - Main Display: Yes 20:33:06 INFO - Mirror: Off 20:33:06 INFO - Online: Yes 20:33:06 INFO - Rotation: Supported 20:33:06 INFO - Return code: 0 20:33:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:33:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:33:06 INFO - Python 2.7.3 20:33:06 INFO - Return code: 0 20:33:06 INFO - grabbing minidump binary from tooltool 20:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:33:06 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 0x108e04d30>, '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 0x108c6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dfcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:33:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:33:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 20:33:06 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:33:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:33:06 INFO - Return code: 0 20:33:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:33:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:33:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:33:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:33:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:33:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:33:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0205 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:33:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T20FBbMdpl/Render', 20:33:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:33:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:33:06 INFO - 'HOME': '/Users/cltbld', 20:33:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:33:06 INFO - 'LOGNAME': 'cltbld', 20:33:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:33:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:33:06 INFO - 'MOZ_NO_REMOTE': '1', 20:33:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:33:06 INFO - 'NO_EM_RESTART': '1', 20:33:06 INFO - 'PAGER': '/bin/cat', 20:33:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:33:06 INFO - 'PWD': '/builds/slave/test', 20:33:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:33:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:33:06 INFO - 'SHELL': '/bin/bash', 20:33:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lIaRcGccNE/Listeners', 20:33:06 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 20:33:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:33:06 INFO - 'USER': 'cltbld', 20:33:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:33:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:33:06 INFO - 'XPC_FLAGS': '0x0', 20:33:06 INFO - 'XPC_SERVICE_NAME': '0', 20:33:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:33:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:33:06 INFO - mozversion application_buildid: 20161226193237 20:33:06 INFO - mozversion application_changeset: 8d9fb8fa1f3db54852a586c145f2ed5afe00f27c 20:33:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:33:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:33:06 INFO - mozversion application_name: Firefox 20:33:06 INFO - mozversion application_remotingname: firefox-dev 20:33:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:33:06 INFO - mozversion application_vendor: Mozilla 20:33:06 INFO - mozversion application_version: 52.0a2 20:33:06 INFO - mozversion platform_buildid: 20161226193237 20:33:06 INFO - mozversion platform_changeset: 8d9fb8fa1f3db54852a586c145f2ed5afe00f27c 20:33:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:33:06 INFO - mozversion platform_version: 52.0a2 20:33:06 INFO - using testdate: 1482813186 20:33:06 INFO - actual date: 1482813186 20:33:06 INFO - starting webserver on 'localhost:49244' 20:33:06 INFO - SUITE-START | Running 2 tests 20:33:06 INFO - TEST-START | dromaeo_css 20:33:06 INFO - operating with platform_type : mac_ 20:33:06 INFO - Initialising browser for dromaeo_css test... 20:33:06 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpz3jbzh/profile http://localhost:49244/getInfo.html) 20:33:09 INFO - PROCESS | 1649 | 2016-12-26 20:33:09.988 plugin-container[1651:8317] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 20:33:09 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:33:09 INFO - PROCESS | 1649 | 2016-12-26 20:33:09.991 plugin-container[1651:8317] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 20:33:09 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:33:09 INFO - PROCESS | 1649 | 2016-12-26 20:33:09.991 plugin-container[1651:8317] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:33:10 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 20:33:10 INFO - PROCESS | 1649 | colorDepth:24 20:33:10 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 20:33:10 INFO - PROCESS | 1649 | __metrics 20:33:10 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 20:33:10 INFO - TEST-INFO | 1649: exit 0 20:33:10 INFO - Browser initialized. 20:33:10 INFO - Running cycle 1/1 for dromaeo_css test... 20:33:10 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpz3jbzh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:33:13 INFO - PROCESS | 1653 | 2016-12-26 20:33:13.969 plugin-container[1654:8443] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8543, name = 'com.apple.tsm.portname' 20:33:13 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:33:13 INFO - PROCESS | 1653 | 2016-12-26 20:33:13.970 plugin-container[1654:8443] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4507, name = 'com.apple.CFPasteboardClient' 20:33:13 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:33:13 INFO - PROCESS | 1653 | 2016-12-26 20:33:13.970 plugin-container[1654:8443] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:33:23 INFO - PROCESS | 1653 | RSS: Main: 229449728 20:33:23 INFO - PROCESS | 1653 | 20:34:13 INFO - PROCESS | 1653 | 1482813253262 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] 20:36:27 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 20:36:29 INFO - PROCESS | 1653 | RSS: Main: 234119168 20:36:29 INFO - PROCESS | 1653 | 20:39:31 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 20:39:32 INFO - PROCESS | 1653 | RSS: Main: 243576832 20:39:32 INFO - PROCESS | 1653 | 20:42:34 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 20:42:34 INFO - PROCESS | 1653 | RSS: Main: 246394880 20:42:34 INFO - PROCESS | 1653 | 20:45:36 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 20:45:37 INFO - PROCESS | 1653 | RSS: Main: 245641216 20:45:37 INFO - PROCESS | 1653 | 20:48:40 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 20:48:40 INFO - PROCESS | 1653 | RSS: Main: 249118720 20:48:40 INFO - PROCESS | 1653 | 20:51:43 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 20:51:43 INFO - PROCESS | 1653 | RSS: Main: 250818560 20:51:43 INFO - PROCESS | 1653 | 20:51:43 INFO - PROCESS | 1653 | __start_tp_report 20:51:43 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 20:51:43 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 20:51:43 INFO - PROCESS | 1653 | |i|pagename|runs| 20:51:43 INFO - PROCESS | 1653 | |0;dojo.html;3330.100872764354;3400.8129552874593;3403.166224275053;3408.267222547152;3424.212431140836;5706.714664266786;5706.771729130835;5734.942650573493;5778.5377169826415;5790.652560846353;5744.511716504092;5790.971045144779;5803.419658034194;5821.912671309934;5830.737616807243;3862.6523612874835;5180.922286165692;5259.447757985414;5278.2082687596785;5342.332208473941;5294.894102117955;5316.840494785143;5351.438098999607;5351.491608297206;5415.214793854889;5160.045391602565;5239.21411788231;5321.068812957717;5329.920051199235;5336.332958380202;4141.378793181017;5237.004969055905;5250.317458730379;5251.684898906078;5278.683279003272;5161.5870730341485;5189.532942035235;5223.973880130613;5237.528622423962;5246.160614301693;4723.858284251443;5304.283921670563;5688.146777983295;5690.374058853523;5692.715364231772;5128.640995130342;6181.227346581677;6184.041473571604;6186.690665466709;6196.845078873019;6223.906641400383;6234.376562343775;6253.280872699653;6278.937210627881;6285.9057114143325;5874.441928016831;5891.617044892111;5911.822645320647;5919.970400148015;5929.644221346733;6211.534134939898;6328.082428047907;6345.460635845912;6368.904466432915;6374.298827129012;6079.878402431927;6132.325444201134;6138.877222455615;6142.437386315926;6258.123862659231;6270.623762574261;6284.528660350492;6288.434040936246;6289.119523266746;6308.148399965946;6045.244344456943;6235.469985051322;6261.7182226799905;6304.243490781044;6313.305536390993;6121.605402471149;6145.8156255312415;6183.598066125687;6185.103160041769;6201.379862013853;4920.458749537548;4923.655344125948;4932.556069953722;4974.403071631355;4998.150314446553;4407.074514351958;4433.534478879736;4433.889152771207;4437.889052773642;4480.059187570581;4107.829268658418;5185.325907632059;5690.857728556736;5730.914036289459;5902.085176797603;6199.907001394983;6203.503719702413;6225.159603453566;6247.718852651642;6322.114903913456;4869.975650121727;5888.440598143169;5900.881982360329;5923.733431995571;5937.168796368512;5443.863903402446;5444.074507333684;5457.590680698884;5479.287692599936;5490.862728431741;5231.921521177109;6187.876242475215;6238.781642642486;6242.563020588515;6268.028455589391;6147.108669242845;6198.721057552511;6328.6519241442165;6329.968350158219;6330.8100756977365;6263.655498947602;6318.05229215603;6347.714352854225;6414.743410263537;6426.614403135827;6098.634081955097;6118.847028824315;6262.2172228471445;6270.372962703876;6288.402601752826;6035.155078289131;6194.690265486617;6238.126662267377;6251.6874156292915;6258.655773932477;6067.908981365194;6123.14276001369;6171.7531298749345;6172.259328880563;6172.814815555541;6140.846478837886;6144.38556144382;6152.661603611844;6154.230721159855;6196.907046394217;5837.620554663933;5872.324682661549;5992.580519363777;6027.75888964454;6034.2155519782145;6113.633182009094;6127.325994140552;6152.723127459186;6200.410960958882;6207.6275423474735;6027.095935609694;6047.909281360695;6050;6056.06131049688;6092.603980741238 20:51:43 INFO - PROCESS | 1653 | |1;ext.html;458.37203031846366;466.8202741944352;481.47038257916296;482.5512273585566;490.93617829682137;34562.135946601345;37228.81385593072;37281.44077838834;37351;37415.438768418426;37373.43939840904;37722.62277377225;37744;37886.621133788656;37902.052448688795;8891.64433422664;9144.359894807367;9149.130832570896;9149.908500914973;9172.082791720808;9144.771380715501;9218.400803947721;9255.981841997374;9257.907420925772;9264.953675231614;9206.309526785484;9234.769130771718;9257.907420925805;9268.351215414923;9273.211776998964;9175.587098580547;9221.262299016063;9230.261579073725;9252.8612070819;9253.583588738524;9081.909180908173;9165.037671044578;9182;9191.724248272563;9199.126083022102;28977.42045159106;30496;32529.349413011845;36925.815370922974;36944.891653250445;3272.361889431562;3292.6048874135017;3302.438585440457;3380.560527131457;3473.3574288756663;27481;28153.57769633457;28328.433431331257;28881.277968050967;28958.986435474657;2676.317539027555;3094.8297843618593;3144.3554071415306;3146.2449012237125;3245.448273793461;371319;376242;376274.11862940784;376497;377809;54437;54476.45523544793;54745.45254547483;54842.72578637161;55006.72496637491;47546.28680569794;56604.433955659915;56903;57145;57186;12883.227006379648;15348.693026139637;15515.224238788016;15582.9220853895;15595.142267175414;12141.453634586465;12158.574449894211;12202.938985305194;12203.69490762738;12255.325957072268;11990.700232494082;13892.23592702411;14751.852481475047;14770.40918363275;14941.551753447415;5977.22296101504;5987.341392446828;6042.909356359658;6124.081387791867;6124.448799608057;796.9601519923981;831.8170002599419;884.4118661090303;980.5538479991586;1025.2003437318904;27524.862375687993;28561.714382855906;29272;29777;30426;871.5032431514103;909.140861885525;976.6874600127891;980.3970558106855;1091.912646988239;14172.787408188886;14366.56900292972;14407.927960360132;14465.349059292357;14701.705965880836;659367;665637;667163;668404;669371;49728.50271497239;49948.75025624849;50227.49772502228;50351.24473132906;50386.24420633693;1356.9660758481116;1361.8297712785902;1375.3329635126956;1380.806687063812;1382.2881216173478;21283;26508.8674556626;27292;27715.722842771313;27822;44866.77566612146;45142.548574515145;45195.548044519135;45399.54600453953;45480.772596136805;597941;599164;601324;601526;603198;24265.39336516601;24425.14511992072;24460.87769561141;24606.630900536864;24733.628995564723;41179.7941010293;41219;41290.38064428976;41733.791331043154;41779.58220417878;34508.30983380369;34748.65251347454;34774.13064673403;34807.47788783221;34859.82570087133;34834.47748283727;34906;34906;35628.82185589056;35758.28483430369;34252.657473424944;35020.82489587536;35997.82001089978;36003;37026.814865925495;30052.84973575118;30257.69742302549;32708.672913270562;32742;32766.344673105927;24504.264872053867;24562.263132106065;24764.50470990606;25054.997800087793;25168.37079073038 20:51:43 INFO - PROCESS | 1653 | |2;jquery.html;274.84333929660096;359.36751317680876;369.7292738301304;376.76640482900604;397.28687006822753;18157.7276340855;18770.4368868934;19813.801861981377;20025;20041.498962525908;11835.230710003865;14972.401103955855;18645.16096775645;19280.614387712238;19299.228030878785;5292.523672869441;5404;5417.187421886709;5450.127979523277;5519.172124181365;5378.354597448311;5396.460353964611;5495.807646732365;5976.641401515923;5995.640261584319;4713.717176969376;5415.620906536544;5889.85275368115;5970.611910225822;5971.761129554812;4550.294704320836;5271.762770675329;5379.9731001345135;5751.7699292028265;5800.825975220729;5288.947110528922;5295.841124766263;5302;5302.734863256822;5307.708076055816;12705.364731763375;12790.61628151157;12974.091813573054;13312.800307995389;13463.66340841477;2105.5683584865064;2234.7653496382973;2323.384303159664;2342.9297121086393;2398.688170537819;9572.617095316178;10137.543810528545;10210.00173555149;10390.480475976246;10494.160467162608;1579.7235483790291;1838.1636355458234;1852.8239817217343;1926.9421917342363;1928.797476264986;117837.41081294538;119687;119704;120320;120749;11474.770504589862;11634;11790.174687771945;11794.823077653842;11832.230904991147;14608.977371583887;14749.336279867432;14750;14759.92620036893;14797.630059248604;8296.377771667148;8472.534010629352;8475.74572762818;8483.533405662747;8670.739877803677;6901.551399159039;8402.621882015324;8581.828363432696;8600.655973761104;8636.95681521601;4434.3348497724955;4587.3807036050375;4589.54104589545;4637.489876113625;4655.813767449264;1875.1374367790968;1970.3202395173641;1971.5366888781125;1975.07171629334;2011.6077364913995;369.2577918384067;375.80494028987533;414.77187546849115;804.3967024731452;925.8055808280202;11602.477888495037;11711.707207319885;11873.703157421134;11880.762384752257;11895.04839612829;426.8143357639437;428.2911781002446;429.48676331783844;442.2018257046063;442.4226384568105;5949.583529152917;5969.880602387928;6075.513958883279;6162.599431036968;6207.534434917399;520382;526974.3651281795;528329;632941;637287;110395;110899.44550277197;116699;126731;127846.72153278535;822.9300509456763;918.6876462002951;975.9512024398751;976.7118699983522;978.8107449448954;18274.81213721104;18438.26246950107;18782.624347513247;18783.71824422662;18848.811511884705;51351.71620709512;54486.727566361915;54898;55189.1721624184;56443.58891027757;68721;79567.60216198882;79910;80171;80583.59708201421;6856.965715171392;7459.962700186681;7775.805604859924;8032.277095061472;8104.068032176375;9853.753656158653;12376.504939802668;12501.43743531525;12675.619731408073;12897.548585799452;10291.279610427198;10629.627963021258;10789.676309710721;11033.558657653604;11091.500882460146;9514.476703781358;10780.838287425851;10800.513976871216;10884;11000.339979601249;5683.630564013326;6198.81403557894;10708.571657133627;10711.982361675628;11124.332540047622;8093.838123237385;8133.430659853753;8215.137410571804;8223.136570660234;8304.750857474286;9959.502024898871;10493.2130090242;10805.837912431163;11056.502457389597;11060.059894909024 20:51:43 INFO - PROCESS | 1653 | |3;mootools.html;1437.1736251655295;1466.0470694048868;1618.996222342148;1737.070667193052;1766.8233176682334;7530.9246907530905;7740.419468539854;7976.48152870063;8483.490990540571;8500.91499085009;6670.499712521556;8257.50454972702;8282.296004839596;8350.206730360622;8350.290225330855;2324.697789287399;2340.4733934864603;2347.190219374313;2348.894299756515;2357.95284094318;2326.767323267668;2356.2931120663816;2379.2862141357523;2380.559596474649;2383.332666853284;2313.6413855852284;2316.444053427174;2342.894569744357;2344.8593084414906;2353.9529209415887;2297.7012988311494;2301.7583153768946;2322.198841399714;2324.59319619066;2330.0330362899376;2320.825938054653;2344.4138965258685;2347.5657003454417;2351.870647114408;2358.563665721847;8346.916530834675;8355.206255405727;8442.15578442151;8461.450005749606;8524.403291769579;5065.974670126662;5092.210707340369;5224.085784987612;5254.474552544753;5255.395629502618;3036.4230796148663;3408.659134086596;3493.0917961329983;3528.7649322737093;3595.7482976191677;1078.7734575739025;1086.3916206924148;1665.6040803315207;1682.9565669285;1769.8495627871646;10938.617148399848;10984.176186785942;11001.669949901436;11069.778604427947;11084.390358530278;4466.665000124939;4645.488996210414;4802;4817.542333478314;4820.758962051884;8662.696805611773;8745.994210665855;8786.86819697705;8793.38446308765;8795.25240354577;5173.922391164136;5178.715170665574;5179.7928082877015;5185.351831021122;5200.973995130076;4138.6482149017675;4789.473157952622;4815.253635686474;4852.878678033077;4884.8290309839695;3649.61679023704;3703.814809259526;3749.5687995880276;3756.3238617048646;3787.659110680052;988.3427520698655;1126.6676330482528;1128.6501184632791;1157.9363135027486;1160.651804458676;762.9313361797465;877.1272583779181;898.8919257600116;903.2322525852973;922.9538523073819;5465.262189604432;5521.089020311603;5592;5777.133429985536;5880.764769409261;964.3056998960737;982.8083523712808;1001.9699409017741;1003.1373019203479;1034.2398337222135;2914.577386278978;3826.253880493333;3855.4216867469545;3895.6883449323986;4214.873553793391;9799.902000979899;10112.292139550307;10126;10127.898721012843;10177.440240786827;9504.239660827117;9894.950525247472;9910.851337229948;10037.548310326052;10063.798724025479;6408.839779005562;7827.412944029106;7854.450188486865;7854.8821767673535;7866.056073271244;5023.34696489454;5173.508516690908;5498.340199176125;5715.77110921154;5869.589128760946;7908.960455197917;9353.11145441182;9450.810983780424;9454.14912657864;9487.905120948979;8943.463392196489;9467.857982130412;9529.857052144085;9548.61805527799;9590.136887680143;6471.449926756279;7177.210506844139;7232.855342893218;7302.780916572511;7348.889766653611;7998.48009879356;8061.395395345256;8065.395095367989;8066.798330041796;8079.192080792109;7056.470764692771;7161.57030578167;7202.89195662055;7216.170140433917;7275.454340924346;5930.940690593039;6910.205326387329;6998.125234345707;7058.188308344606;7089.61007144612;6637;6649.0691303218555;6696.430803381572;6721.193456785021;6735.124433823572;4868.464468908346;4898.681585696918;4916.508349165055;4938.259261110862;4955.82654607087;4807.927881081717;4813.446453657734;4892.657513973988;4899.804007839789;5027.12025395547 20:51:43 INFO - PROCESS | 1653 | |4;prototype.html;300.54917623564666;304.0877367896311;306.3260826182399;311.24523031648255;314.78122704720215;4371.912561748763;4640.871836620537;5364.980653675799;5550.555955523559;5604.439556044394;5262.158054711247;5349.251104845315;5381.919271210935;5438;5511.807086751965;3268.7711860169857;3457.602375726786;3527.876524321636;4194.496660400756;4215.40984262202;3817.9618203817886;4201.722656328069;4221.176870510251;4232.3016702244095;4238.279492486269;3559.928801423983;4083.6528895043957;4125.814338354766;4126.46355973723;4132.40080188374;3737.402015677506;3827.961720382816;4088.9795551022203;4132.235536425771;4166.895827604304;3032.6664066952617;3182.411253918033;3636.3999940009867;3643.453481977698;3648.452732090181;4281.608477244877;4284;5152.62503060496;5335.386430560497;5372.140457526777;4216.388623649585;4751.05206865187;4770.618350531984;4786.617070634341;4837.85486435404;5669.886602267973;5692.5161361284345;5708.229389032469;5763.559110222444;5767.711614419262;3169.3661267746543;3842.423636454554;3852.2664800839734;3897.7466464679705;4025.9798701006307;4965.354503914468;5681.346645135821;5980.671063091529;6000.909986350208;6057.848553786146;3913.8630147945255;3954.54522729884;4002.939955900664;4007.178528401729;4012.538558065801;3115.735162511212;3302.877021812595;3626.8005259710967;4013.7591744495426;4181.790910454526;3928.7642741435034;3950.4666869972184;4626.514216007338;4637.3275874997935;4691.296305554194;4631.444226692818;5790.36306006339;5848.152017957457;5933.762649494059;5968.612040217372;1086.7120213143523;1239.7148655809215;1252.486480542973;1269.5302737987004;1315.276597871167;875.2866413872673;875.8335916175906;879.6877108626474;889.0575989451203;895.6641259527677;3964.52425708911;4296.033392486728;4868.756562171877;5128.615193934838;5132.255822905658;4924.482929292444;5062.27882519945;5644.701718604661;5723.427657234327;5757.798477053283;4813.7352445615825;4841.685290456179;5055.772490237948;5059.306706104564;5063.822766203239;2572.4855028994275;2633.6839579250613;2729.6042073899175;2748.100753704821;2942.573326867592;4537.727736335764;4788.281757736298;4832.81596008973;5223.004401632754;5509.752061157257;4738.95261047392;5419.258149119521;5461.563074953994;5572.442755724395;5718.342390625048;5171.3277273954145;5360.764995653468;5399.627370766113;5514.696691681996;5945.40545945402;4774.546418090275;4988.575971042502;5508.887478574593;5567.7494512747835;5702.372738998624;5144.30551875492;5272.736363181903;5559;5639.605227634026;5644.6330988485615;4768.713877167381;5423;5467.289252397163;5947.3942035651;5950.7917222896995;3572.820969080249;3686.723495737777;3762.9623703762613;3783.5270591176104;3793.336166170964;5013.899722005466;5288.81489147878;5516.089845175662;5753.597248192586;5781.479666830005;4476.485204201428;4862.055177928715;5150.639455238247;5230.320058392384;5572.60991730575;4887.7556122194455;5378.494021673938;5380.031594313061;5441.21102440136;5516.675997760589;4515.841945531891;4640.86077417678;4966.751662416792;5505.366882808528;5515.696636685021;4882.780274887709;4974.676646017997;5695.629784064023;5699.116636921284;5736.799212027559;4311.568843115663;5129.7691603877165;5218.530332270114;5661.264035675335;5695 20:51:43 INFO - PROCESS | 1653 | |5;yui.html;667.4193451697024;671.7010930136088;731.8499707559951;747.0885519666007;783.9176886426927;14004.929975350122;14384.352704128327;14845.183514906703;14851.777223341658;14950.850491495083;12093.334866582334;13440.596782096529;13490.662733431645;13521.256330901797;13569.321533923314;791.7743443118698;802.2859654907137;811.3428123220181;812.2405550810012;812.6424373275739;800.1278606319111;809.2191035650596;809.5830647216688;812.5733989655448;812.8455593437258;855.1918437076964;859.0550394565959;860.9225169734723;861.4142383179435;863.4862256957132;831.8669012957959;832.2551316571664;849.9915000850006;852.9872051919195;856.2764464027887;638.0524920493064;638.3712043637014;638.5721566550422;639.4660458517144;641.4515589171285;12271.938640306831;12346.382680865921;12531;12538.74922501554;12674.74650506994;11139.33164010162;11216.046636035946;11359.80722024192;11388.943055284753;11579.305241685526;10218.79562408755;10550.155987521057;10797.082248008928;10878.075363594104;11096;4213.178430206111;4241.511991865866;4288.056627541936;4335.956640433618;4449.132419178261;79929.20070799276;82181;83761.99999999939;83861;86093;9807.75480612976;10054.145397641283;10057.547410366551;10115.645952391631;10121.240906931862;16053.759193612104;18628.068596570116;18770.906145469184;19179.520511987314;19198.232070717015;7282.890756638654;7284.70861165558;7309.5248808827255;7385.3722433592675;7399.630018499053;4053.91892162161;4065.959340406558;4139.151473947833;4141.979290103591;4310.7629080400875;3358.7648864579733;3440.088376580208;3445.87939422119;3457.17027913299;3469.757117001836;622.0296337713182;655.5476721062452;659.0279337976447;666.8899631560738;670.6479098473341;621.5245337316957;629.8929182039064;641.1760887259849;659.2023651381882;666.2005593287977;8784.297256219486;8825.823483530294;8904.395124469009;9111.27109831212;9146.634134634673;513.6070905757099;651.5048563092084;657.8651376467812;660.2044536333707;679.8810208213542;4264.680148988777;4424.557544245549;4426.712263702849;4440.2673558862425;4464.933026004613;76203.47593048109;104774.95225047652;117185;117856.8214317863;118709;99900;104186.95813041924;104436.47781761043;104472;106521.46739266254;88472.99999999872;89178.55410722905;89680;91765;92298.53850730837;8572.571371431528;8640.827183456422;8689.0876457971;8702.47785132894;8728.69449569262;44706.77646611746;56905.71547142403;58162.709186453794;59032.52418689567;59415;49328;52710.736446317525;63492.04761928667;65527;69761;12230.205036672944;12347.13570050088;12383.318917459266;12402.875971240534;12658.177218480769;13271;13599;13916.095453795471;14125.152490850582;14510.201938893462;12429.378531073475;12998.285094319524;13140.60578182656;13150.07949443568;13588.728225435634;12448.564300249811;13273.535426260421;13500.98742594329;13553.322333883463;13656.590302290948;11888.227265227733;12539.184952978027;12768.106232564003;12819.16675416095;12960.611181664564;10393.480325983823;10603.363798172135;11044.116470682487;11328.490217940047;11434.656960291204;9590.856137157809;10273.845892311472;11103.021114889969;11126.109911206922;11367.54529818831 20:51:43 INFO - PROCESS | 1653 | __end_tp_report 20:51:43 INFO - PROCESS | 1653 | __start_cc_report 20:51:43 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,365 20:51:43 INFO - PROCESS | 1653 | __end_cc_report 20:51:43 INFO - PROCESS | 1653 | __startTimestamp1482814303494__endTimestamp 20:51:43 INFO - PROCESS | 1653 | 20:51:43 INFO - PROCESS | 1653 | ------- Summary: start ------- 20:51:43 INFO - PROCESS | 1653 | Number of tests: 6 20:51:43 INFO - PROCESS | 1653 | 20:51:43 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5731.84 Median:6034.22 stddev:681.37 (11.3%) stddev-sans-first:656.89 20:51:43 INFO - PROCESS | 1653 | Values: 3330.1 3400.8 3403.2 3408.3 3424.2 5706.7 5706.8 5734.9 5778.5 5790.7 5744.5 5791.0 5803.4 5821.9 5830.7 3862.7 5180.9 5259.4 5278.2 5342.3 5294.9 5316.8 5351.4 5351.5 5415.2 5160.0 5239.2 5321.1 5329.9 5336.3 4141.4 5237.0 5250.3 5251.7 5278.7 5161.6 5189.5 5224.0 5237.5 5246.2 4723.9 5304.3 5688.1 5690.4 5692.7 5128.6 6181.2 6184.0 6186.7 6196.8 6223.9 6234.4 6253.3 6278.9 6285.9 5874.4 5891.6 5911.8 5920.0 5929.6 6211.5 6328.1 6345.5 6368.9 6374.3 6079.9 6132.3 6138.9 6142.4 6258.1 6270.6 6284.5 6288.4 6289.1 6308.1 6045.2 6235.5 6261.7 6304.2 6313.3 6121.6 6145.8 6183.6 6185.1 6201.4 4920.5 4923.7 4932.6 4974.4 4998.2 4407.1 4433.5 4433.9 4437.9 4480.1 4107.8 5185.3 5690.9 5730.9 5902.1 6199.9 6203.5 6225.2 6247.7 6322.1 4870.0 5888.4 5900.9 5923.7 5937.2 5443.9 5444.1 5457.6 5479.3 5490.9 5231.9 6187.9 6238.8 6242.6 6268.0 6147.1 6198.7 6328.7 6330.0 6330.8 6263.7 6318.1 6347.7 6414.7 6426.6 6098.6 6118.8 6262.2 6270.4 6288.4 6035.2 6194.7 6238.1 6251.7 6258.7 6067.9 6123.1 6171.8 6172.3 6172.8 6140.8 6144.4 6152.7 6154.2 6196.9 5837.6 5872.3 5992.6 6027.8 6034.2 6113.6 6127.3 6152.7 6200.4 6207.6 6027.1 6047.9 6050.0 6056.1 6092.6 20:51:43 INFO - PROCESS | 1653 | 20:51:43 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:66236.42 Median:37316.22 stddev:150717.56 (403.9%) stddev-sans-first:151059.45 20:51:43 INFO - PROCESS | 1653 | Values: 458.4 466.8 481.5 482.6 490.9 34562.1 37228.8 37281.4 37351.0 37415.4 37373.4 37722.6 37744.0 37886.6 37902.1 8891.6 9144.4 9149.1 9149.9 9172.1 9144.8 9218.4 9256.0 9257.9 9265.0 9206.3 9234.8 9257.9 9268.4 9273.2 9175.6 9221.3 9230.3 9252.9 9253.6 9081.9 9165.0 9182.0 9191.7 9199.1 28977.4 30496.0 32529.3 36925.8 36944.9 3272.4 3292.6 3302.4 3380.6 3473.4 27481.0 28153.6 28328.4 28881.3 28959.0 2676.3 3094.8 3144.4 3146.2 3245.4 371319.0 376242.0 376274.1 376497.0 377809.0 54437.0 54476.5 54745.5 54842.7 55006.7 47546.3 56604.4 56903.0 57145.0 57186.0 12883.2 15348.7 15515.2 15582.9 15595.1 12141.5 12158.6 12202.9 12203.7 12255.3 11990.7 13892.2 14751.9 14770.4 14941.6 5977.2 5987.3 6042.9 6124.1 6124.4 797.0 831.8 884.4 980.6 1025.2 27524.9 28561.7 29272.0 29777.0 30426.0 871.5 909.1 976.7 980.4 1091.9 14172.8 14366.6 14407.9 14465.3 14701.7 659367.0 665637.0 667163.0 668404.0 669371.0 49728.5 49948.8 50227.5 50351.2 50386.2 1357.0 1361.8 1375.3 1380.8 1382.3 21283.0 26508.9 27292.0 27715.7 27822.0 44866.8 45142.5 45195.5 45399.5 45480.8 597941.0 599164.0 601324.0 601526.0 603198.0 24265.4 24425.1 24460.9 24606.6 24733.6 41179.8 41219.0 41290.4 41733.8 41779.6 34508.3 34748.7 34774.1 34807.5 34859.8 34834.5 34906.0 34906.0 35628.8 35758.3 34252.7 35020.8 35997.8 36003.0 37026.8 30052.8 30257.7 32708.7 32742.0 32766.3 24504.3 24562.3 24764.5 25055.0 25168.4 20:51:43 INFO - PROCESS | 1653 | 20:51:43 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:32992.11 Median:406.03 stddev:95717.90 (23574.1%) stddev-sans-first:95954.89 20:51:43 INFO - PROCESS | 1653 | Values: 274.8 359.4 369.7 376.8 397.3 18157.7 18770.4 19813.8 20025.0 20041.5 11835.2 14972.4 18645.2 19280.6 19299.2 5292.5 5404.0 5417.2 5450.1 5519.2 5378.4 5396.5 5495.8 5976.6 5995.6 4713.7 5415.6 5889.9 5970.6 5971.8 4550.3 5271.8 5380.0 5751.8 5800.8 5288.9 5295.8 5302.0 5302.7 5307.7 12705.4 12790.6 12974.1 13312.8 13463.7 2105.6 2234.8 2323.4 2342.9 2398.7 9572.6 10137.5 10210.0 10390.5 10494.2 1579.7 1838.2 1852.8 1926.9 1928.8 117837.4 119687.0 119704.0 120320.0 120749.0 11474.8 11634.0 11790.2 11794.8 11832.2 14609.0 14749.3 14750.0 14759.9 14797.6 8296.4 8472.5 8475.7 8483.5 8670.7 6901.6 8402.6 8581.8 8600.7 8637.0 4434.3 4587.4 4589.5 4637.5 4655.8 1875.1 1970.3 1971.5 1975.1 2011.6 369.3 375.8 414.8 804.4 925.8 11602.5 11711.7 11873.7 11880.8 11895.0 426.8 428.3 429.5 442.2 442.4 5949.6 5969.9 6075.5 6162.6 6207.5 520382.0 526974.4 528329.0 632941.0 637287.0 110395.0 110899.4 116699.0 126731.0 127846.7 822.9 918.7 976.0 976.7 978.8 18274.8 18438.3 18782.6 18783.7 18848.8 51351.7 54486.7 54898.0 55189.2 56443.6 68721.0 79567.6 79910.0 80171.0 80583.6 6857.0 7460.0 7775.8 8032.3 8104.1 9853.8 12376.5 12501.4 12675.6 12897.5 10291.3 10629.6 10789.7 11033.6 11091.5 9514.5 10780.8 10800.5 10884.0 11000.3 5683.6 6198.8 10708.6 10712.0 11124.3 8093.8 8133.4 8215.1 8223.1 8304.8 9959.5 10493.2 10805.8 11056.5 11060.1 20:51:43 INFO - PROCESS | 1653 | 20:51:43 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5428.43 Median:5132.86 stddev:2930.30 (57.1%) stddev-sans-first:2923.17 20:51:43 INFO - PROCESS | 1653 | Values: 1437.2 1466.0 1619.0 1737.1 1766.8 7530.9 7740.4 7976.5 8483.5 8500.9 6670.5 8257.5 8282.3 8350.2 8350.3 2324.7 2340.5 2347.2 2348.9 2358.0 2326.8 2356.3 2379.3 2380.6 2383.3 2313.6 2316.4 2342.9 2344.9 2354.0 2297.7 2301.8 2322.2 2324.6 2330.0 2320.8 2344.4 2347.6 2351.9 2358.6 8346.9 8355.2 8442.2 8461.5 8524.4 5066.0 5092.2 5224.1 5254.5 5255.4 3036.4 3408.7 3493.1 3528.8 3595.7 1078.8 1086.4 1665.6 1683.0 1769.8 10938.6 10984.2 11001.7 11069.8 11084.4 4466.7 4645.5 4802.0 4817.5 4820.8 8662.7 8746.0 8786.9 8793.4 8795.3 5173.9 5178.7 5179.8 5185.4 5201.0 4138.6 4789.5 4815.3 4852.9 4884.8 3649.6 3703.8 3749.6 3756.3 3787.7 988.3 1126.7 1128.7 1157.9 1160.7 762.9 877.1 898.9 903.2 923.0 5465.3 5521.1 5592.0 5777.1 5880.8 964.3 982.8 1002.0 1003.1 1034.2 2914.6 3826.3 3855.4 3895.7 4214.9 9799.9 10112.3 10126.0 10127.9 10177.4 9504.2 9895.0 9910.9 10037.5 10063.8 6408.8 7827.4 7854.5 7854.9 7866.1 5023.3 5173.5 5498.3 5715.8 5869.6 7909.0 9353.1 9450.8 9454.1 9487.9 8943.5 9467.9 9529.9 9548.6 9590.1 6471.4 7177.2 7232.9 7302.8 7348.9 7998.5 8061.4 8065.4 8066.8 8079.2 7056.5 7161.6 7202.9 7216.2 7275.5 5930.9 6910.2 6998.1 7058.2 7089.6 6637.0 6649.1 6696.4 6721.2 6735.1 4868.5 4898.7 4916.5 4938.3 4955.8 4807.9 4813.4 4892.7 4899.8 5027.1 20:51:43 INFO - PROCESS | 1653 | 20:51:43 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4436.73 Median:4875.77 stddev:1370.80 (28.1%) stddev-sans-first:1339.03 20:51:43 INFO - PROCESS | 1653 | Values: 300.5 304.1 306.3 311.2 314.8 4371.9 4640.9 5365.0 5550.6 5604.4 5262.2 5349.3 5381.9 5438.0 5511.8 3268.8 3457.6 3527.9 4194.5 4215.4 3818.0 4201.7 4221.2 4232.3 4238.3 3559.9 4083.7 4125.8 4126.5 4132.4 3737.4 3828.0 4089.0 4132.2 4166.9 3032.7 3182.4 3636.4 3643.5 3648.5 4281.6 4284.0 5152.6 5335.4 5372.1 4216.4 4751.1 4770.6 4786.6 4837.9 5669.9 5692.5 5708.2 5763.6 5767.7 3169.4 3842.4 3852.3 3897.7 4026.0 4965.4 5681.3 5980.7 6000.9 6057.8 3913.9 3954.5 4002.9 4007.2 4012.5 3115.7 3302.9 3626.8 4013.8 4181.8 3928.8 3950.5 4626.5 4637.3 4691.3 4631.4 5790.4 5848.2 5933.8 5968.6 1086.7 1239.7 1252.5 1269.5 1315.3 875.3 875.8 879.7 889.1 895.7 3964.5 4296.0 4868.8 5128.6 5132.3 4924.5 5062.3 5644.7 5723.4 5757.8 4813.7 4841.7 5055.8 5059.3 5063.8 2572.5 2633.7 2729.6 2748.1 2942.6 4537.7 4788.3 4832.8 5223.0 5509.8 4739.0 5419.3 5461.6 5572.4 5718.3 5171.3 5360.8 5399.6 5514.7 5945.4 4774.5 4988.6 5508.9 5567.7 5702.4 5144.3 5272.7 5559.0 5639.6 5644.6 4768.7 5423.0 5467.3 5947.4 5950.8 3572.8 3686.7 3763.0 3783.5 3793.3 5013.9 5288.8 5516.1 5753.6 5781.5 4476.5 4862.1 5150.6 5230.3 5572.6 4887.8 5378.5 5380.0 5441.2 5516.7 4515.8 4640.9 4966.8 5505.4 5515.7 4882.8 4974.7 5695.6 5699.1 5736.8 4311.6 5129.8 5218.5 5661.3 5695.0 20:51:43 INFO - PROCESS | 1653 | 20:51:43 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:20155.06 Median:4380.26 stddev:30061.89 (686.3%) stddev-sans-first:30110.61 20:51:43 INFO - PROCESS | 1653 | Values: 667.4 671.7 731.8 747.1 783.9 14004.9 14384.4 14845.2 14851.8 14950.9 12093.3 13440.6 13490.7 13521.3 13569.3 791.8 802.3 811.3 812.2 812.6 800.1 809.2 809.6 812.6 812.8 855.2 859.1 860.9 861.4 863.5 831.9 832.3 850.0 853.0 856.3 638.1 638.4 638.6 639.5 641.5 12271.9 12346.4 12531.0 12538.7 12674.7 11139.3 11216.0 11359.8 11388.9 11579.3 10218.8 10550.2 10797.1 10878.1 11096.0 4213.2 4241.5 4288.1 4336.0 4449.1 79929.2 82181.0 83762.0 83861.0 86093.0 9807.8 10054.1 10057.5 10115.6 10121.2 16053.8 18628.1 18770.9 19179.5 19198.2 7282.9 7284.7 7309.5 7385.4 7399.6 4053.9 4066.0 4139.2 4142.0 4310.8 3358.8 3440.1 3445.9 3457.2 3469.8 622.0 655.5 659.0 666.9 670.6 621.5 629.9 641.2 659.2 666.2 8784.3 8825.8 8904.4 9111.3 9146.6 513.6 651.5 657.9 660.2 679.9 4264.7 4424.6 4426.7 4440.3 4464.9 76203.5 104775.0 117185.0 117856.8 118709.0 99900.0 104187.0 104436.5 104472.0 106521.5 88473.0 89178.6 89680.0 91765.0 92298.5 8572.6 8640.8 8689.1 8702.5 8728.7 44706.8 56905.7 58162.7 59032.5 59415.0 49328.0 52710.7 63492.0 65527.0 69761.0 12230.2 12347.1 12383.3 12402.9 12658.2 13271.0 13599.0 13916.1 14125.2 14510.2 12429.4 12998.3 13140.6 13150.1 13588.7 12448.6 13273.5 13501.0 13553.3 13656.6 11888.2 12539.2 12768.1 12819.2 12960.6 10393.5 10603.4 11044.1 11328.5 11434.7 9590.9 10273.8 11103.0 11126.1 11367.5 20:51:43 INFO - PROCESS | 1653 | -------- Summary: end -------- 20:51:43 INFO - PROCESS | 1653 | 20:51:44 INFO - TEST-INFO | 1653: exit 0 20:51:44 INFO - TEST-OK | dromaeo_css | took 1117498ms 20:51:44 INFO - TEST-START | kraken 20:51:44 INFO - operating with platform_type : mac_ 20:51:44 INFO - Initialising browser for kraken test... 20:51:44 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmp6mUtgp/profile http://localhost:49244/getInfo.html) 20:51:47 INFO - PROCESS | 1660 | 2016-12-26 20:51:47.139 plugin-container[1661:10717] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x423b, name = 'com.apple.tsm.portname' 20:51:47 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:51:47 INFO - PROCESS | 1660 | 2016-12-26 20:51:47.142 plugin-container[1661:10717] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 20:51:47 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:51:47 INFO - PROCESS | 1660 | 2016-12-26 20:51:47.142 plugin-container[1661:10717] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:51:47 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 20:51:47 INFO - PROCESS | 1660 | colorDepth:24 20:51:47 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 20:51:47 INFO - PROCESS | 1660 | __metrics 20:51:47 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 20:51:48 INFO - TEST-INFO | 1660: exit 0 20:51:48 INFO - Browser initialized. 20:51:48 INFO - Running cycle 1/1 for kraken test... 20:51:48 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmp6mUtgp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:51:50 INFO - PROCESS | 1662 | 2016-12-26 20:51:50.752 plugin-container[1663:10843] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x884b, name = 'com.apple.tsm.portname' 20:51:50 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:51:50 INFO - PROCESS | 1662 | 2016-12-26 20:51:50.753 plugin-container[1663:10843] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x421b, name = 'com.apple.CFPasteboardClient' 20:51:50 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:51:50 INFO - PROCESS | 1662 | 2016-12-26 20:51:50.753 plugin-container[1663:10843] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:52:00 INFO - PROCESS | 1662 | RSS: Main: 229531648 20:52:00 INFO - PROCESS | 1662 | 20:52:03 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 20:52:04 INFO - PROCESS | 1662 | RSS: Main: 241627136 20:52:04 INFO - PROCESS | 1662 | 20:52:06 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 20:52:07 INFO - PROCESS | 1662 | RSS: Main: 249294848 20:52:07 INFO - PROCESS | 1662 | 20:52:10 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 20:52:11 INFO - PROCESS | 1662 | RSS: Main: 252985344 20:52:11 INFO - PROCESS | 1662 | 20:52:13 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 20:52:14 INFO - PROCESS | 1662 | RSS: Main: 253308928 20:52:14 INFO - PROCESS | 1662 | 20:52:16 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:52:16 INFO - PROCESS | 1662 | RSS: Main: 254607360 20:52:16 INFO - PROCESS | 1662 | 20:52:19 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 20:52:20 INFO - PROCESS | 1662 | RSS: Main: 259219456 20:52:20 INFO - PROCESS | 1662 | 20:52:23 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 20:52:24 INFO - PROCESS | 1662 | RSS: Main: 258068480 20:52:24 INFO - PROCESS | 1662 | 20:52:27 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 20:52:27 INFO - PROCESS | 1662 | RSS: Main: 258195456 20:52:27 INFO - PROCESS | 1662 | 20:52:29 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:52:29 INFO - PROCESS | 1662 | RSS: Main: 258011136 20:52:29 INFO - PROCESS | 1662 | 20:52:32 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:52:32 INFO - PROCESS | 1662 | RSS: Main: 257499136 20:52:32 INFO - PROCESS | 1662 | 20:52:34 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:52:35 INFO - PROCESS | 1662 | RSS: Main: 257347584 20:52:35 INFO - PROCESS | 1662 | 20:52:38 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:52:39 INFO - PROCESS | 1662 | RSS: Main: 257679360 20:52:39 INFO - PROCESS | 1662 | 20:52:42 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:52:42 INFO - PROCESS | 1662 | RSS: Main: 257531904 20:52:42 INFO - PROCESS | 1662 | 20:52:44 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 20:52:45 INFO - PROCESS | 1662 | RSS: Main: 259403776 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | __start_tp_report 20:52:45 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 20:52:45 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 20:52:45 INFO - PROCESS | 1662 | |i|pagename|runs| 20:52:45 INFO - PROCESS | 1662 | |0;ai-astar;107;120;125;135;119;120;117;123;120;121 20:52:45 INFO - PROCESS | 1662 | |1;audio-beat-detection;146;112;112;148;139;152;122;116;136;112 20:52:45 INFO - PROCESS | 1662 | |2;audio-dft;198;201;196;202;198;194;197;284;247;198 20:52:45 INFO - PROCESS | 1662 | |3;audio-fft;80;77;72;110;89;62;60;61;78;60 20:52:45 INFO - PROCESS | 1662 | |4;audio-oscillator;87;71;91;72;69;71;87;69;75;96 20:52:45 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;89;108;138;99;103;86;90;86;91;87 20:52:45 INFO - PROCESS | 1662 | |6;imaging-darkroom;136;98;99;100;98;100;132;149;170;143 20:52:45 INFO - PROCESS | 1662 | |7;imaging-desaturate;86;91;87;87;89;102;90;93;102;123 20:52:45 INFO - PROCESS | 1662 | |8;json-parse-financial;58;65;76;62;63;62;60;62;60;65 20:52:45 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;69;91;60;79;69;97;65;78;106;112 20:52:45 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;85;86;86;84;85;86;86;85;85;84 20:52:45 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;149;164;159;141;139;158;153;157;204;249 20:52:45 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;179;170;168;166;212;156;143;164;218;237 20:52:45 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;60;77;74;64;86;61;75;89;86;88 20:52:45 INFO - PROCESS | 1662 | __end_tp_report 20:52:45 INFO - PROCESS | 1662 | __start_cc_report 20:52:45 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1017 20:52:45 INFO - PROCESS | 1662 | __end_cc_report 20:52:45 INFO - PROCESS | 1662 | __startTimestamp1482814365057__endTimestamp 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | ------- Summary: start ------- 20:52:45 INFO - PROCESS | 1662 | Number of tests: 14 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:120.70 Median:120.50 stddev:6.95 (5.8%) stddev-sans-first:5.31 20:52:45 INFO - PROCESS | 1662 | Values: 107.0 120.0 125.0 135.0 119.0 120.0 117.0 123.0 120.0 121.0 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:137.50 stddev:16.37 (11.9%) stddev-sans-first:16.23 20:52:45 INFO - PROCESS | 1662 | Values: 146.0 112.0 112.0 148.0 139.0 152.0 122.0 116.0 136.0 112.0 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:211.50 Median:199.50 stddev:29.85 (15.0%) stddev-sans-first:31.26 20:52:45 INFO - PROCESS | 1662 | Values: 198.0 201.0 196.0 202.0 198.0 194.0 197.0 284.0 247.0 198.0 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:74.90 Median:74.50 stddev:15.93 (21.4%) stddev-sans-first:16.79 20:52:45 INFO - PROCESS | 1662 | Values: 80.0 77.0 72.0 110.0 89.0 62.0 60.0 61.0 78.0 60.0 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:81.00 stddev:10.29 (12.7%) stddev-sans-first:10.48 20:52:45 INFO - PROCESS | 1662 | Values: 87.0 71.0 91.0 72.0 69.0 71.0 87.0 69.0 75.0 96.0 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:97.70 Median:88.00 stddev:16.08 (18.3%) stddev-sans-first:16.75 20:52:45 INFO - PROCESS | 1662 | Values: 89.0 108.0 138.0 99.0 103.0 86.0 90.0 86.0 91.0 87.0 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:122.50 Median:159.50 stddev:26.70 (16.7%) stddev-sans-first:27.87 20:52:45 INFO - PROCESS | 1662 | Values: 136.0 98.0 99.0 100.0 98.0 100.0 132.0 149.0 170.0 143.0 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:88.00 stddev:11.41 (13.0%) stddev-sans-first:11.63 20:52:45 INFO - PROCESS | 1662 | Values: 86.0 91.0 87.0 87.0 89.0 102.0 90.0 93.0 102.0 123.0 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:62.50 stddev:4.97 (7.9%) stddev-sans-first:4.88 20:52:45 INFO - PROCESS | 1662 | Values: 58.0 65.0 76.0 62.0 63.0 62.0 60.0 62.0 60.0 65.0 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:82.60 Median:73.50 stddev:18.00 (24.5%) stddev-sans-first:18.40 20:52:45 INFO - PROCESS | 1662 | Values: 69.0 91.0 60.0 79.0 69.0 97.0 65.0 78.0 106.0 112.0 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:85.50 stddev:0.79 (0.9%) stddev-sans-first:0.83 20:52:45 INFO - PROCESS | 1662 | Values: 85.0 86.0 86.0 84.0 85.0 86.0 86.0 85.0 85.0 84.0 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:167.30 Median:158.50 stddev:33.87 (21.4%) stddev-sans-first:35.27 20:52:45 INFO - PROCESS | 1662 | Values: 149.0 164.0 159.0 141.0 139.0 158.0 153.0 157.0 204.0 249.0 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.30 Median:174.50 stddev:30.45 (17.4%) stddev-sans-first:32.28 20:52:45 INFO - PROCESS | 1662 | Values: 179.0 170.0 168.0 166.0 212.0 156.0 143.0 164.0 218.0 237.0 20:52:45 INFO - PROCESS | 1662 | 20:52:45 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.00 Median:81.50 stddev:11.27 (13.8%) stddev-sans-first:10.37 20:52:45 INFO - PROCESS | 1662 | Values: 60.0 77.0 74.0 64.0 86.0 61.0 75.0 89.0 86.0 88.0 20:52:45 INFO - PROCESS | 1662 | -------- Summary: end -------- 20:52:45 INFO - PROCESS | 1662 | 20:52:46 INFO - TEST-INFO | 1662: exit 0 20:52:46 INFO - TEST-OK | kraken | took 61886ms 20:52:46 INFO - SUITE-END | took 1179s 20:52:46 INFO - Completed test suite (00:19:39) 20:52:46 INFO - javascript benchmark 20:52:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6954.6781005895555, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.100872764354, 3400.8129552874593, 3403.166224275053, 3408.267222547152, 3424.212431140836, 5706.714664266786, 5706.771729130835, 5734.942650573493, 5778.5377169826415, 5790.652560846353, 5744.511716504092, 5790.971045144779, 5803.419658034194, 5821.912671309934, 5830.737616807243, 3862.6523612874835, 5180.922286165692, 5259.447757985414, 5278.2082687596785, 5342.332208473941, 5294.894102117955, 5316.840494785143, 5351.438098999607, 5351.491608297206, 5415.214793854889, 5160.045391602565, 5239.21411788231, 5321.068812957717, 5329.920051199235, 5336.332958380202, 4141.378793181017, 5237.004969055905, 5250.317458730379, 5251.684898906078, 5278.683279003272, 5161.5870730341485, 5189.532942035235, 5223.973880130613, 5237.528622423962, 5246.160614301693, 4723.858284251443, 5304.283921670563, 5688.146777983295, 5690.374058853523, 5692.715364231772, 5128.640995130342, 6181.227346581677, 6184.041473571604, 6186.690665466709, 6196.845078873019, 6223.906641400383, 6234.376562343775, 6253.280872699653, 6278.937210627881, 6285.9057114143325, 5874.441928016831, 5891.617044892111, 5911.822645320647, 5919.970400148015, 5929.644221346733, 6211.534134939898, 6328.082428047907, 6345.460635845912, 6368.904466432915, 6374.298827129012, 6079.878402431927, 6132.325444201134, 6138.877222455615, 6142.437386315926, 6258.123862659231, 6270.623762574261, 6284.528660350492, 6288.434040936246, 6289.119523266746, 6308.148399965946, 6045.244344456943, 6235.469985051322, 6261.7182226799905, 6304.243490781044, 6313.305536390993, 6121.605402471149, 6145.8156255312415, 6183.598066125687, 6185.103160041769, 6201.379862013853, 4920.458749537548, 4923.655344125948, 4932.556069953722, 4974.403071631355, 4998.150314446553, 4407.074514351958, 4433.534478879736, 4433.889152771207, 4437.889052773642, 4480.059187570581, 4107.829268658418, 5185.325907632059, 5690.857728556736, 5730.914036289459, 5902.085176797603, 6199.907001394983, 6203.503719702413, 6225.159603453566, 6247.718852651642, 6322.114903913456, 4869.975650121727, 5888.440598143169, 5900.881982360329, 5923.733431995571, 5937.168796368512, 5443.863903402446, 5444.074507333684, 5457.590680698884, 5479.287692599936, 5490.862728431741, 5231.921521177109, 6187.876242475215, 6238.781642642486, 6242.563020588515, 6268.028455589391, 6147.108669242845, 6198.721057552511, 6328.6519241442165, 6329.968350158219, 6330.8100756977365, 6263.655498947602, 6318.05229215603, 6347.714352854225, 6414.743410263537, 6426.614403135827, 6098.634081955097, 6118.847028824315, 6262.2172228471445, 6270.372962703876, 6288.402601752826, 6035.155078289131, 6194.690265486617, 6238.126662267377, 6251.6874156292915, 6258.655773932477, 6067.908981365194, 6123.14276001369, 6171.7531298749345, 6172.259328880563, 6172.814815555541, 6140.846478837886, 6144.38556144382, 6152.661603611844, 6154.230721159855, 6196.907046394217, 5837.620554663933, 5872.324682661549, 5992.580519363777, 6027.75888964454, 6034.2155519782145, 6113.633182009094, 6127.325994140552, 6152.723127459186, 6200.410960958882, 6207.6275423474735, 6027.095935609694, 6047.909281360695, 6050.0, 6056.06131049688, 6092.603980741238], "value": 5689.607527757853, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.37203031846366, 466.8202741944352, 481.47038257916296, 482.5512273585566, 490.93617829682137, 34562.135946601345, 37228.81385593072, 37281.44077838834, 37351.0, 37415.438768418426, 37373.43939840904, 37722.62277377225, 37744.0, 37886.621133788656, 37902.052448688795, 8891.64433422664, 9144.359894807367, 9149.130832570896, 9149.908500914973, 9172.082791720808, 9144.771380715501, 9218.400803947721, 9255.981841997374, 9257.907420925772, 9264.953675231614, 9206.309526785484, 9234.769130771718, 9257.907420925805, 9268.351215414923, 9273.211776998964, 9175.587098580547, 9221.262299016063, 9230.261579073725, 9252.8612070819, 9253.583588738524, 9081.909180908173, 9165.037671044578, 9182.0, 9191.724248272563, 9199.126083022102, 28977.42045159106, 30496.0, 32529.349413011845, 36925.815370922974, 36944.891653250445, 3272.361889431562, 3292.6048874135017, 3302.438585440457, 3380.560527131457, 3473.3574288756663, 27481.0, 28153.57769633457, 28328.433431331257, 28881.277968050967, 28958.986435474657, 2676.317539027555, 3094.8297843618593, 3144.3554071415306, 3146.2449012237125, 3245.448273793461, 371319.0, 376242.0, 376274.11862940784, 376497.0, 377809.0, 54437.0, 54476.45523544793, 54745.45254547483, 54842.72578637161, 55006.72496637491, 47546.28680569794, 56604.433955659915, 56903.0, 57145.0, 57186.0, 12883.227006379648, 15348.693026139637, 15515.224238788016, 15582.9220853895, 15595.142267175414, 12141.453634586465, 12158.574449894211, 12202.938985305194, 12203.69490762738, 12255.325957072268, 11990.700232494082, 13892.23592702411, 14751.852481475047, 14770.40918363275, 14941.551753447415, 5977.22296101504, 5987.341392446828, 6042.909356359658, 6124.081387791867, 6124.448799608057, 796.9601519923981, 831.8170002599419, 884.4118661090303, 980.5538479991586, 1025.2003437318904, 27524.862375687993, 28561.714382855906, 29272.0, 29777.0, 30426.0, 871.5032431514103, 909.140861885525, 976.6874600127891, 980.3970558106855, 1091.912646988239, 14172.787408188886, 14366.56900292972, 14407.927960360132, 14465.349059292357, 14701.705965880836, 659367.0, 665637.0, 667163.0, 668404.0, 669371.0, 49728.50271497239, 49948.75025624849, 50227.49772502228, 50351.24473132906, 50386.24420633693, 1356.9660758481116, 1361.8297712785902, 1375.3329635126956, 1380.806687063812, 1382.2881216173478, 21283.0, 26508.8674556626, 27292.0, 27715.722842771313, 27822.0, 44866.77566612146, 45142.548574515145, 45195.548044519135, 45399.54600453953, 45480.772596136805, 597941.0, 599164.0, 601324.0, 601526.0, 603198.0, 24265.39336516601, 24425.14511992072, 24460.87769561141, 24606.630900536864, 24733.628995564723, 41179.7941010293, 41219.0, 41290.38064428976, 41733.791331043154, 41779.58220417878, 34508.30983380369, 34748.65251347454, 34774.13064673403, 34807.47788783221, 34859.82570087133, 34834.47748283727, 34906.0, 34906.0, 35628.82185589056, 35758.28483430369, 34252.657473424944, 35020.82489587536, 35997.82001089978, 36003.0, 37026.814865925495, 30052.84973575118, 30257.69742302549, 32708.672913270562, 32742.0, 32766.344673105927, 24504.264872053867, 24562.263132106065, 24764.50470990606, 25054.997800087793, 25168.37079073038], "value": 18344.86648582703, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.84333929660096, 359.36751317680876, 369.7292738301304, 376.76640482900604, 397.28687006822753, 18157.7276340855, 18770.4368868934, 19813.801861981377, 20025.0, 20041.498962525908, 11835.230710003865, 14972.401103955855, 18645.16096775645, 19280.614387712238, 19299.228030878785, 5292.523672869441, 5404.0, 5417.187421886709, 5450.127979523277, 5519.172124181365, 5378.354597448311, 5396.460353964611, 5495.807646732365, 5976.641401515923, 5995.640261584319, 4713.717176969376, 5415.620906536544, 5889.85275368115, 5970.611910225822, 5971.761129554812, 4550.294704320836, 5271.762770675329, 5379.9731001345135, 5751.7699292028265, 5800.825975220729, 5288.947110528922, 5295.841124766263, 5302.0, 5302.734863256822, 5307.708076055816, 12705.364731763375, 12790.61628151157, 12974.091813573054, 13312.800307995389, 13463.66340841477, 2105.5683584865064, 2234.7653496382973, 2323.384303159664, 2342.9297121086393, 2398.688170537819, 9572.617095316178, 10137.543810528545, 10210.00173555149, 10390.480475976246, 10494.160467162608, 1579.7235483790291, 1838.1636355458234, 1852.8239817217343, 1926.9421917342363, 1928.797476264986, 117837.41081294538, 119687.0, 119704.0, 120320.0, 120749.0, 11474.770504589862, 11634.0, 11790.174687771945, 11794.823077653842, 11832.230904991147, 14608.977371583887, 14749.336279867432, 14750.0, 14759.92620036893, 14797.630059248604, 8296.377771667148, 8472.534010629352, 8475.74572762818, 8483.533405662747, 8670.739877803677, 6901.551399159039, 8402.621882015324, 8581.828363432696, 8600.655973761104, 8636.95681521601, 4434.3348497724955, 4587.3807036050375, 4589.54104589545, 4637.489876113625, 4655.813767449264, 1875.1374367790968, 1970.3202395173641, 1971.5366888781125, 1975.07171629334, 2011.6077364913995, 369.2577918384067, 375.80494028987533, 414.77187546849115, 804.3967024731452, 925.8055808280202, 11602.477888495037, 11711.707207319885, 11873.703157421134, 11880.762384752257, 11895.04839612829, 426.8143357639437, 428.2911781002446, 429.48676331783844, 442.2018257046063, 442.4226384568105, 5949.583529152917, 5969.880602387928, 6075.513958883279, 6162.599431036968, 6207.534434917399, 520382.0, 526974.3651281795, 528329.0, 632941.0, 637287.0, 110395.0, 110899.44550277197, 116699.0, 126731.0, 127846.72153278535, 822.9300509456763, 918.6876462002951, 975.9512024398751, 976.7118699983522, 978.8107449448954, 18274.81213721104, 18438.26246950107, 18782.624347513247, 18783.71824422662, 18848.811511884705, 51351.71620709512, 54486.727566361915, 54898.0, 55189.1721624184, 56443.58891027757, 68721.0, 79567.60216198882, 79910.0, 80171.0, 80583.59708201421, 6856.965715171392, 7459.962700186681, 7775.805604859924, 8032.277095061472, 8104.068032176375, 9853.753656158653, 12376.504939802668, 12501.43743531525, 12675.619731408073, 12897.548585799452, 10291.279610427198, 10629.627963021258, 10789.676309710721, 11033.558657653604, 11091.500882460146, 9514.476703781358, 10780.838287425851, 10800.513976871216, 10884.0, 11000.339979601249, 5683.630564013326, 6198.81403557894, 10708.571657133627, 10711.982361675628, 11124.332540047622, 8093.838123237385, 8133.430659853753, 8215.137410571804, 8223.136570660234, 8304.750857474286, 9959.502024898871, 10493.2130090242, 10805.837912431163, 11056.502457389597, 11060.059894909024], "value": 8505.971023629068, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.1736251655295, 1466.0470694048868, 1618.996222342148, 1737.070667193052, 1766.8233176682334, 7530.9246907530905, 7740.419468539854, 7976.48152870063, 8483.490990540571, 8500.91499085009, 6670.499712521556, 8257.50454972702, 8282.296004839596, 8350.206730360622, 8350.290225330855, 2324.697789287399, 2340.4733934864603, 2347.190219374313, 2348.894299756515, 2357.95284094318, 2326.767323267668, 2356.2931120663816, 2379.2862141357523, 2380.559596474649, 2383.332666853284, 2313.6413855852284, 2316.444053427174, 2342.894569744357, 2344.8593084414906, 2353.9529209415887, 2297.7012988311494, 2301.7583153768946, 2322.198841399714, 2324.59319619066, 2330.0330362899376, 2320.825938054653, 2344.4138965258685, 2347.5657003454417, 2351.870647114408, 2358.563665721847, 8346.916530834675, 8355.206255405727, 8442.15578442151, 8461.450005749606, 8524.403291769579, 5065.974670126662, 5092.210707340369, 5224.085784987612, 5254.474552544753, 5255.395629502618, 3036.4230796148663, 3408.659134086596, 3493.0917961329983, 3528.7649322737093, 3595.7482976191677, 1078.7734575739025, 1086.3916206924148, 1665.6040803315207, 1682.9565669285, 1769.8495627871646, 10938.617148399848, 10984.176186785942, 11001.669949901436, 11069.778604427947, 11084.390358530278, 4466.665000124939, 4645.488996210414, 4802.0, 4817.542333478314, 4820.758962051884, 8662.696805611773, 8745.994210665855, 8786.86819697705, 8793.38446308765, 8795.25240354577, 5173.922391164136, 5178.715170665574, 5179.7928082877015, 5185.351831021122, 5200.973995130076, 4138.6482149017675, 4789.473157952622, 4815.253635686474, 4852.878678033077, 4884.8290309839695, 3649.61679023704, 3703.814809259526, 3749.5687995880276, 3756.3238617048646, 3787.659110680052, 988.3427520698655, 1126.6676330482528, 1128.6501184632791, 1157.9363135027486, 1160.651804458676, 762.9313361797465, 877.1272583779181, 898.8919257600116, 903.2322525852973, 922.9538523073819, 5465.262189604432, 5521.089020311603, 5592.0, 5777.133429985536, 5880.764769409261, 964.3056998960737, 982.8083523712808, 1001.9699409017741, 1003.1373019203479, 1034.2398337222135, 2914.577386278978, 3826.253880493333, 3855.4216867469545, 3895.6883449323986, 4214.873553793391, 9799.902000979899, 10112.292139550307, 10126.0, 10127.898721012843, 10177.440240786827, 9504.239660827117, 9894.950525247472, 9910.851337229948, 10037.548310326052, 10063.798724025479, 6408.839779005562, 7827.412944029106, 7854.450188486865, 7854.8821767673535, 7866.056073271244, 5023.34696489454, 5173.508516690908, 5498.340199176125, 5715.77110921154, 5869.589128760946, 7908.960455197917, 9353.11145441182, 9450.810983780424, 9454.14912657864, 9487.905120948979, 8943.463392196489, 9467.857982130412, 9529.857052144085, 9548.61805527799, 9590.136887680143, 6471.449926756279, 7177.210506844139, 7232.855342893218, 7302.780916572511, 7348.889766653611, 7998.48009879356, 8061.395395345256, 8065.395095367989, 8066.798330041796, 8079.192080792109, 7056.470764692771, 7161.57030578167, 7202.89195662055, 7216.170140433917, 7275.454340924346, 5930.940690593039, 6910.205326387329, 6998.125234345707, 7058.188308344606, 7089.61007144612, 6637.0, 6649.0691303218555, 6696.430803381572, 6721.193456785021, 6735.124433823572, 4868.464468908346, 4898.681585696918, 4916.508349165055, 4938.259261110862, 4955.82654607087, 4807.927881081717, 4813.446453657734, 4892.657513973988, 4899.804007839789, 5027.12025395547], "value": 4445.1295857077475, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.54917623564666, 304.0877367896311, 306.3260826182399, 311.24523031648255, 314.78122704720215, 4371.912561748763, 4640.871836620537, 5364.980653675799, 5550.555955523559, 5604.439556044394, 5262.158054711247, 5349.251104845315, 5381.919271210935, 5438.0, 5511.807086751965, 3268.7711860169857, 3457.602375726786, 3527.876524321636, 4194.496660400756, 4215.40984262202, 3817.9618203817886, 4201.722656328069, 4221.176870510251, 4232.3016702244095, 4238.279492486269, 3559.928801423983, 4083.6528895043957, 4125.814338354766, 4126.46355973723, 4132.40080188374, 3737.402015677506, 3827.961720382816, 4088.9795551022203, 4132.235536425771, 4166.895827604304, 3032.6664066952617, 3182.411253918033, 3636.3999940009867, 3643.453481977698, 3648.452732090181, 4281.608477244877, 4284.0, 5152.62503060496, 5335.386430560497, 5372.140457526777, 4216.388623649585, 4751.05206865187, 4770.618350531984, 4786.617070634341, 4837.85486435404, 5669.886602267973, 5692.5161361284345, 5708.229389032469, 5763.559110222444, 5767.711614419262, 3169.3661267746543, 3842.423636454554, 3852.2664800839734, 3897.7466464679705, 4025.9798701006307, 4965.354503914468, 5681.346645135821, 5980.671063091529, 6000.909986350208, 6057.848553786146, 3913.8630147945255, 3954.54522729884, 4002.939955900664, 4007.178528401729, 4012.538558065801, 3115.735162511212, 3302.877021812595, 3626.8005259710967, 4013.7591744495426, 4181.790910454526, 3928.7642741435034, 3950.4666869972184, 4626.514216007338, 4637.3275874997935, 4691.296305554194, 4631.444226692818, 5790.36306006339, 5848.152017957457, 5933.762649494059, 5968.612040217372, 1086.7120213143523, 1239.7148655809215, 1252.486480542973, 1269.5302737987004, 1315.276597871167, 875.2866413872673, 875.8335916175906, 879.6877108626474, 889.0575989451203, 895.6641259527677, 3964.52425708911, 4296.033392486728, 4868.756562171877, 5128.615193934838, 5132.255822905658, 4924.482929292444, 5062.27882519945, 5644.701718604661, 5723.427657234327, 5757.798477053283, 4813.7352445615825, 4841.685290456179, 5055.772490237948, 5059.306706104564, 5063.822766203239, 2572.4855028994275, 2633.6839579250613, 2729.6042073899175, 2748.100753704821, 2942.573326867592, 4537.727736335764, 4788.281757736298, 4832.81596008973, 5223.004401632754, 5509.752061157257, 4738.95261047392, 5419.258149119521, 5461.563074953994, 5572.442755724395, 5718.342390625048, 5171.3277273954145, 5360.764995653468, 5399.627370766113, 5514.696691681996, 5945.40545945402, 4774.546418090275, 4988.575971042502, 5508.887478574593, 5567.7494512747835, 5702.372738998624, 5144.30551875492, 5272.736363181903, 5559.0, 5639.605227634026, 5644.6330988485615, 4768.713877167381, 5423.0, 5467.289252397163, 5947.3942035651, 5950.7917222896995, 3572.820969080249, 3686.723495737777, 3762.9623703762613, 3783.5270591176104, 3793.336166170964, 5013.899722005466, 5288.81489147878, 5516.089845175662, 5753.597248192586, 5781.479666830005, 4476.485204201428, 4862.055177928715, 5150.639455238247, 5230.320058392384, 5572.60991730575, 4887.7556122194455, 5378.494021673938, 5380.031594313061, 5441.21102440136, 5516.675997760589, 4515.841945531891, 4640.86077417678, 4966.751662416792, 5505.366882808528, 5515.696636685021, 4882.780274887709, 4974.676646017997, 5695.629784064023, 5699.116636921284, 5736.799212027559, 4311.568843115663, 5129.7691603877165, 5218.530332270114, 5661.264035675335, 5695.0], "value": 4004.0028248339095, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.4193451697024, 671.7010930136088, 731.8499707559951, 747.0885519666007, 783.9176886426927, 14004.929975350122, 14384.352704128327, 14845.183514906703, 14851.777223341658, 14950.850491495083, 12093.334866582334, 13440.596782096529, 13490.662733431645, 13521.256330901797, 13569.321533923314, 791.7743443118698, 802.2859654907137, 811.3428123220181, 812.2405550810012, 812.6424373275739, 800.1278606319111, 809.2191035650596, 809.5830647216688, 812.5733989655448, 812.8455593437258, 855.1918437076964, 859.0550394565959, 860.9225169734723, 861.4142383179435, 863.4862256957132, 831.8669012957959, 832.2551316571664, 849.9915000850006, 852.9872051919195, 856.2764464027887, 638.0524920493064, 638.3712043637014, 638.5721566550422, 639.4660458517144, 641.4515589171285, 12271.938640306831, 12346.382680865921, 12531.0, 12538.74922501554, 12674.74650506994, 11139.33164010162, 11216.046636035946, 11359.80722024192, 11388.943055284753, 11579.305241685526, 10218.79562408755, 10550.155987521057, 10797.082248008928, 10878.075363594104, 11096.0, 4213.178430206111, 4241.511991865866, 4288.056627541936, 4335.956640433618, 4449.132419178261, 79929.20070799276, 82181.0, 83761.99999999939, 83861.0, 86093.0, 9807.75480612976, 10054.145397641283, 10057.547410366551, 10115.645952391631, 10121.240906931862, 16053.759193612104, 18628.068596570116, 18770.906145469184, 19179.520511987314, 19198.232070717015, 7282.890756638654, 7284.70861165558, 7309.5248808827255, 7385.3722433592675, 7399.630018499053, 4053.91892162161, 4065.959340406558, 4139.151473947833, 4141.979290103591, 4310.7629080400875, 3358.7648864579733, 3440.088376580208, 3445.87939422119, 3457.17027913299, 3469.757117001836, 622.0296337713182, 655.5476721062452, 659.0279337976447, 666.8899631560738, 670.6479098473341, 621.5245337316957, 629.8929182039064, 641.1760887259849, 659.2023651381882, 666.2005593287977, 8784.297256219486, 8825.823483530294, 8904.395124469009, 9111.27109831212, 9146.634134634673, 513.6070905757099, 651.5048563092084, 657.8651376467812, 660.2044536333707, 679.8810208213542, 4264.680148988777, 4424.557544245549, 4426.712263702849, 4440.2673558862425, 4464.933026004613, 76203.47593048109, 104774.95225047652, 117185.0, 117856.8214317863, 118709.0, 99900.0, 104186.95813041924, 104436.47781761043, 104472.0, 106521.46739266254, 88472.99999999872, 89178.55410722905, 89680.0, 91765.0, 92298.53850730837, 8572.571371431528, 8640.827183456422, 8689.0876457971, 8702.47785132894, 8728.69449569262, 44706.77646611746, 56905.71547142403, 58162.709186453794, 59032.52418689567, 59415.0, 49328.0, 52710.736446317525, 63492.04761928667, 65527.0, 69761.0, 12230.205036672944, 12347.13570050088, 12383.318917459266, 12402.875971240534, 12658.177218480769, 13271.0, 13599.0, 13916.095453795471, 14125.152490850582, 14510.201938893462, 12429.378531073475, 12998.285094319524, 13140.60578182656, 13150.07949443568, 13588.728225435634, 12448.564300249811, 13273.535426260421, 13500.98742594329, 13553.322333883463, 13656.590302290948, 11888.227265227733, 12539.184952978027, 12768.106232564003, 12819.16675416095, 12960.611181664564, 10393.480325983823, 10603.363798172135, 11044.116470682487, 11328.490217940047, 11434.656960291204, 9590.856137157809, 10273.845892311472, 11103.021114889969, 11126.109911206922, 11367.54529818831], "value": 7160.105833272755, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1586.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 120.0, 125.0, 135.0, 119.0, 120.0, 117.0, 123.0, 120.0, 121.0], "value": 120.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 112.0, 112.0, 148.0, 139.0, 152.0, 122.0, 116.0, 136.0, 112.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 201.0, 196.0, 202.0, 198.0, 194.0, 197.0, 284.0, 247.0, 198.0], "value": 211.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 77.0, 72.0, 110.0, 89.0, 62.0, 60.0, 61.0, 78.0, 60.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 91.0, 72.0, 69.0, 71.0, 87.0, 69.0, 75.0, 96.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 108.0, 138.0, 99.0, 103.0, 86.0, 90.0, 86.0, 91.0, 87.0], "value": 97.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 98.0, 99.0, 100.0, 98.0, 100.0, 132.0, 149.0, 170.0, 143.0], "value": 122.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 91.0, 87.0, 87.0, 89.0, 102.0, 90.0, 93.0, 102.0, 123.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 65.0, 76.0, 62.0, 63.0, 62.0, 60.0, 62.0, 60.0, 65.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 91.0, 60.0, 79.0, 69.0, 97.0, 65.0, 78.0, 106.0, 112.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 86.0, 84.0, 85.0, 86.0, 86.0, 85.0, 85.0, 84.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 164.0, 159.0, 141.0, 139.0, 158.0, 153.0, 157.0, 204.0, 249.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 170.0, 168.0, 166.0, 212.0, 156.0, 143.0, 164.0, 218.0, 237.0], "value": 181.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 77.0, 74.0, 64.0, 86.0, 61.0, 75.0, 89.0, 86.0, 88.0], "value": 76.0, "unit": "score"}]}]} 20:52:46 INFO - Return code: 0 20:52:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:52:46 ERROR - # TBPL SUCCESS # 20:52:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:52:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:52:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:52:46 INFO - 2016-12-26 20:52:46.376 screenresolution[1667:11231] starting screenresolution argv=screenresolution get 20:52:46 INFO - 2016-12-26 20:52:46.380 screenresolution[1667:11231] Display 0: 1600x1200x32@60 20:52:46 INFO - 2016-12-26 20:52:46.387 screenresolution[1668:11234] starting screenresolution argv=screenresolution list 20:52:46 INFO - Available Modes on Display 0 20:52:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:52:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:52:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:52:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:52:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:52:46 INFO - Intel Iris: 20:52:46 INFO - Chipset Model: Intel Iris 20:52:46 INFO - Type: GPU 20:52:46 INFO - Bus: Built-In 20:52:46 INFO - VRAM (Dynamic, Max): 1536 MB 20:52:46 INFO - Vendor: Intel (0x8086) 20:52:46 INFO - Device ID: 0x0a2e 20:52:46 INFO - Revision ID: 0x0009 20:52:46 INFO - Displays: 20:52:46 INFO - Display: 20:52:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:52:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:52:46 INFO - Display Serial Number: 1600x1200 60 20:52:46 INFO - Main Display: Yes 20:52:46 INFO - Mirror: Off 20:52:46 INFO - Online: Yes 20:52:46 INFO - Rotation: Supported 20:52:46 INFO - Return code: 0 20:52:46 INFO - Running post-action listener: _resource_record_post_action 20:52:46 INFO - [mozharness: 2016-12-27 04:52:46.626987Z] Finished run-tests step (success) 20:52:46 INFO - Running post-run listener: _resource_record_post_run 20:52:46 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 31977984; Write bytes: 662442496; Read time: 198; Write time: 1901 20:52:46 INFO - TinderboxPrint: CPU usage
54.4% 20:52:46 INFO - TinderboxPrint: I/O read bytes / time
31,977,984 / 198 20:52:46 INFO - TinderboxPrint: I/O write bytes / time
662,442,496 / 1,901 20:52:46 INFO - TinderboxPrint: CPU idle
2,211.4 (45.6%) 20:52:46 INFO - TinderboxPrint: CPU system
977.6 (20.2%) 20:52:46 INFO - TinderboxPrint: CPU user
1,656.8 (34.2%) 20:52:46 INFO - TinderboxPrint: Swap in / out
377,204,736 / 0 20:52:46 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344726016; Write bytes: 346777600; Read time: 26985; Write time: 709 20:52:46 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 30003712; Write bytes: 308795904; Read time: 180; Write time: 1164 20:52:47 INFO - Running post-run listener: _upload_blobber_files 20:52:47 INFO - Blob upload gear active. 20:52:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:52:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:52:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:52:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:52:47 INFO - (blobuploader) - INFO - Open directory for files ... 20:52:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:52:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:52:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:52:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:52:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:52:49 INFO - (blobuploader) - INFO - Done attempting. 20:52:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:52:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:52:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:52:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:52:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:52:50 INFO - (blobuploader) - INFO - Done attempting. 20:52:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:52:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:52:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:52:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:52:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:52:52 INFO - (blobuploader) - INFO - Done attempting. 20:52:52 INFO - (blobuploader) - INFO - Iteration through files over. 20:52:52 INFO - Return code: 0 20:52:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:52:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:52:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f258f8743fcce0fbce4d4d8022f195f65847081df02717abf7208779ef37db156d792d5888ffba0c14e4425b07a4b0c1036a1ee4084aab4e5996cade4805466e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17e7cc61fc75dc81578d8e86d4a43b04eac49d7074ffab4d8813fe2c6fe07d72cd83c6aabdbb310d4677f07fe74d384bcd0cacfb3ecdf406489b0e419cc754d6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/019cca9cc27f5faf62258fe1987b40052f2af385440475539a4b38609e1d75e93f9ec01a391d0a03898ee2680f89a2d52182f3dc6ff2c13920efe7e60a337aa2"} 20:52:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:52:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:52:52 INFO - Contents: 20:52:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f258f8743fcce0fbce4d4d8022f195f65847081df02717abf7208779ef37db156d792d5888ffba0c14e4425b07a4b0c1036a1ee4084aab4e5996cade4805466e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17e7cc61fc75dc81578d8e86d4a43b04eac49d7074ffab4d8813fe2c6fe07d72cd83c6aabdbb310d4677f07fe74d384bcd0cacfb3ecdf406489b0e419cc754d6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/019cca9cc27f5faf62258fe1987b40052f2af385440475539a4b38609e1d75e93f9ec01a391d0a03898ee2680f89a2d52182f3dc6ff2c13920efe7e60a337aa2"} 20:52:52 INFO - Running post-run listener: copy_logs_to_upload_dir 20:52:52 INFO - Copying logs to upload dir... 20:52:52 INFO - mkdir: /builds/slave/test/build/upload/logs 20:52:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.021181 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-26 20:52:52.385007) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 20:52:52.388812) ========= 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.T20FBbMdpl/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.lIaRcGccNE/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f258f8743fcce0fbce4d4d8022f195f65847081df02717abf7208779ef37db156d792d5888ffba0c14e4425b07a4b0c1036a1ee4084aab4e5996cade4805466e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17e7cc61fc75dc81578d8e86d4a43b04eac49d7074ffab4d8813fe2c6fe07d72cd83c6aabdbb310d4677f07fe74d384bcd0cacfb3ecdf406489b0e419cc754d6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/019cca9cc27f5faf62258fe1987b40052f2af385440475539a4b38609e1d75e93f9ec01a391d0a03898ee2680f89a2d52182f3dc6ff2c13920efe7e60a337aa2"} build_url:https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011601 build_url: 'https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f258f8743fcce0fbce4d4d8022f195f65847081df02717abf7208779ef37db156d792d5888ffba0c14e4425b07a4b0c1036a1ee4084aab4e5996cade4805466e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17e7cc61fc75dc81578d8e86d4a43b04eac49d7074ffab4d8813fe2c6fe07d72cd83c6aabdbb310d4677f07fe74d384bcd0cacfb3ecdf406489b0e419cc754d6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/019cca9cc27f5faf62258fe1987b40052f2af385440475539a4b38609e1d75e93f9ec01a391d0a03898ee2680f89a2d52182f3dc6ff2c13920efe7e60a337aa2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 20:52:52.424033) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 20:52:52.424387) ========= 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.T20FBbMdpl/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.lIaRcGccNE/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.005164 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 20:52:52.473543) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 20:52:52.473856) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 20:52:52.474220) ========= ========= Total master_lag: 3.31 =========