builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0136 starttime: 1479161543.43 results: success (0) buildid: 20161114112842 builduid: 37b75e2f2cc64a04813759037c6ea815 revision: 3f2202420b01fcc253118f5dcc07f1be9b3c3009 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 14:12:23.429525) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 14:12:23.430014) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 14:12:23.430320) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 14:12:23.459608) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 14:12:23.460004) ========= 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.ioL8MabQ4N/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.cMGegJizu2/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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-11-14 14:12:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.63M=0.002s 2016-11-14 14:12:23 (6.63 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.098919 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 14:12:23.580753) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 14:12:23.581081) ========= 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.ioL8MabQ4N/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.cMGegJizu2/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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.019945 ========= master_lag: 0.13 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 14:12:23.732536) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 14:12:23.732898) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 3f2202420b01fcc253118f5dcc07f1be9b3c3009 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 3f2202420b01fcc253118f5dcc07f1be9b3c3009 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ioL8MabQ4N/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.cMGegJizu2/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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-11-14 14:12:23,813 truncating revision to first 12 chars 2016-11-14 14:12:23,813 Setting DEBUG logging. 2016-11-14 14:12:23,813 attempt 1/10 2016-11-14 14:12:23,813 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/3f2202420b01?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 14:12:24,933 unpacking tar archive at: larch-3f2202420b01/testing/mozharness/ program finished with exit code 0 elapsedTime=1.361042 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 14:12:25.122258) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 14:12:25.122624) ========= script_repo_revision: 3f2202420b01fcc253118f5dcc07f1be9b3c3009 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 14:12:25.123059) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 14:12:25.123353) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 14:12:25.142042) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-14 14:12:25.142468) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ioL8MabQ4N/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.cMGegJizu2/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 14:12:25 INFO - MultiFileLogger online at 20161114 14:12:25 in /builds/slave/test 14:12:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 14:12:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:12:25 INFO - {'append_to_log': False, 14:12:25 INFO - 'base_work_dir': '/builds/slave/test', 14:12:25 INFO - 'blob_upload_branch': 'Larch', 14:12:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:12:25 INFO - 'branch': 'Larch', 14:12:25 INFO - 'buildbot_json_path': 'buildprops.json', 14:12:25 INFO - 'config_files': ('talos/mac_config.py',), 14:12:25 INFO - 'default_actions': ('clobber', 14:12:25 INFO - 'read-buildbot-config', 14:12:25 INFO - 'download-and-extract', 14:12:25 INFO - 'populate-webroot', 14:12:25 INFO - 'create-virtualenv', 14:12:25 INFO - 'install', 14:12:25 INFO - 'run-tests'), 14:12:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:12:25 INFO - 'download_minidump_stackwalk': True, 14:12:25 INFO - 'download_symbols': 'ondemand', 14:12:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:12:25 INFO - 'tooltool.py': '/tools/tooltool.py', 14:12:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:12:25 INFO - '/tools/misc-python/virtualenv.py')}, 14:12:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:12:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:12:25 INFO - 'installer_path': 'installer.exe', 14:12:25 INFO - 'log_level': 'info', 14:12:25 INFO - 'log_name': 'talos', 14:12:25 INFO - 'log_to_console': True, 14:12:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:12:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:12:25 INFO - 'opt_config_files': (), 14:12:25 INFO - 'pip_index': False, 14:12:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:12:25 INFO - 'cmd': ('bash', 14:12:25 INFO - '-c', 14:12:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:12:25 INFO - 'enabled': True, 14:12:25 INFO - 'halt_on_failure': False, 14:12:25 INFO - 'name': 'check_screen_resolution'},), 14:12:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:12:25 INFO - 'cmd': ('bash', 14:12:25 INFO - '-c', 14:12:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:12:25 INFO - 'enabled': True, 14:12:25 INFO - 'halt_on_failure': False, 14:12:25 INFO - 'name': 'check_screen_resolution'},), 14:12:25 INFO - 'run_cmd_checks_enabled': True, 14:12:25 INFO - 'sps_profile': False, 14:12:25 INFO - 'sps_profile_interval': 0, 14:12:25 INFO - 'suite': 'dromaeojs', 14:12:25 INFO - 'system_bits': '32', 14:12:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:12:25 INFO - 'title': 't-yosemite-r7-0136', 14:12:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:12:25 INFO - 'use_talos_json': True, 14:12:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:12:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:12:25 INFO - 'work_dir': 'build'} 14:12:25 INFO - [mozharness: 2016-11-14 22:12:25.344900Z] Running clobber step. 14:12:25 INFO - Running pre-action listener: _resource_record_pre_action 14:12:25 INFO - Running main action method: clobber 14:12:25 INFO - rmtree: /builds/slave/test/build 14:12:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:12:26 INFO - Running post-action listener: _resource_record_post_action 14:12:26 INFO - [mozharness: 2016-11-14 22:12:26.311852Z] Finished clobber step (success) 14:12:26 INFO - [mozharness: 2016-11-14 22:12:26.312095Z] Running read-buildbot-config step. 14:12:26 INFO - Running pre-action listener: _resource_record_pre_action 14:12:26 INFO - Running main action method: read_buildbot_config 14:12:26 INFO - Using buildbot properties: 14:12:26 INFO - { 14:12:26 INFO - "project": "", 14:12:26 INFO - "product": "firefox", 14:12:26 INFO - "script_repo_revision": "production", 14:12:26 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 14:12:26 INFO - "repository": "", 14:12:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 14:12:26 INFO - "buildid": "20161114112842", 14:12:26 INFO - "pgo_build": "False", 14:12:26 INFO - "basedir": "/builds/slave/test", 14:12:26 INFO - "buildnumber": 2, 14:12:26 INFO - "slavename": "t-yosemite-r7-0136", 14:12:26 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:12:26 INFO - "platform": "yosemite_r7", 14:12:26 INFO - "scheduler": "tests-larch-macosx64-talos", 14:12:26 INFO - "branch": "larch", 14:12:26 INFO - "repo_path": "projects/larch", 14:12:26 INFO - "revision": "3f2202420b01fcc253118f5dcc07f1be9b3c3009", 14:12:26 INFO - "stage_platform": "macosx64", 14:12:26 INFO - "builduid": "37b75e2f2cc64a04813759037c6ea815", 14:12:26 INFO - "slavebuilddir": "test" 14:12:26 INFO - } 14:12:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 14:12:26 INFO - Running post-action listener: _resource_record_post_action 14:12:26 INFO - [mozharness: 2016-11-14 22:12:26.317312Z] Finished read-buildbot-config step (success) 14:12:26 INFO - [mozharness: 2016-11-14 22:12:26.317422Z] Running download-and-extract step. 14:12:26 INFO - Running pre-action listener: _resource_record_pre_action 14:12:26 INFO - Running main action method: download_and_extract 14:12:26 INFO - mkdir: /builds/slave/test/build/tests 14:12:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:26 INFO - trying https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 14:12:26 INFO - Downloading https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 14:12:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 14:12:27 INFO - Downloaded 1199 bytes. 14:12:27 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 14:12:27 INFO - Using the following test package requirements: 14:12:27 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:12:27 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:27 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 14:12:27 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:27 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 14:12:27 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:27 INFO - u'jsshell-mac.zip'], 14:12:27 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:27 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 14:12:27 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:12:27 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:27 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 14:12:27 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:27 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 14:12:27 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:27 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 14:12:27 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:27 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 14:12:27 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:12:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:12:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:12:27 INFO - Fetch https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:12:28 INFO - Http code: 200 14:12:28 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 14:12:28 INFO - x-amz-cf-id: DcN-UxYJOCBg6MSQS0GRqoENDe5lwAS9pM5nLAQfJdB0X7ayfepEWQ== 14:12:28 INFO - x-amz-version-id: bd0CBy5FohgvI7VblRCFf8TFpwtMGnc1 14:12:28 INFO - Expected file size: 16328498 14:12:28 INFO - Obtained file size: 16328498 14:12:35 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:12:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:12:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:12:35 INFO - Fetch https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:12:35 INFO - Http code: 200 14:12:35 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 14:12:35 INFO - x-amz-cf-id: HZWQysyLCoScL7hBfBgRlbYgZKj12zuc8JtWOzXXc4J8BJQybPUxUQ== 14:12:35 INFO - x-amz-version-id: bd0CBy5FohgvI7VblRCFf8TFpwtMGnc1 14:12:36 INFO - Expected file size: 16328498 14:12:36 INFO - Obtained file size: 16328498 14:12:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 14:12:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:12:42 INFO - Fetch https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 14:12:43 INFO - Http code: 200 14:12:43 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 14:12:43 INFO - x-amz-cf-id: k_Lr6v1_OW8ImRwkkH1PM1-LClP89sjVVogzTwAdVaUNBLNDWKGoBg== 14:12:43 INFO - x-amz-version-id: rzK2BKOyNhH_C.AtM0Ic_KG.n3PRgzg9 14:12:43 INFO - Expected file size: 14409038 14:12:43 INFO - Obtained file size: 14409038 14:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:43 INFO - trying https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:12:43 INFO - Downloading https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 14:12:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 14:12:46 INFO - Downloaded 110980766 bytes. 14:12:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:12:46 INFO - mkdir: /builds/slave/test/properties 14:12:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:12:46 INFO - Writing to file /builds/slave/test/properties/build_url 14:12:46 INFO - Contents: 14:12:46 INFO - build_url:https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:12:47 INFO - Running post-action listener: _resource_record_post_action 14:12:47 INFO - Running post-action listener: set_extra_try_arguments 14:12:47 INFO - [mozharness: 2016-11-14 22:12:47.361747Z] Finished download-and-extract step (success) 14:12:47 INFO - [mozharness: 2016-11-14 22:12:47.361879Z] Running populate-webroot step. 14:12:47 INFO - Running pre-action listener: _resource_record_pre_action 14:12:47 INFO - Running main action method: populate_webroot 14:12:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:12:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:12:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:12:47 INFO - '--tppagecycles', 14:12:47 INFO - '7'], 14:12:47 INFO - 'tests': ['tsvgm']}}, 14:12:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:12:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:12:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:12:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:12:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:12:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:12:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:47 INFO - 'talos_options': ['--disable-e10s'], 14:12:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:12:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:12:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:47 INFO - 'talos_options': ['--disable-e10s'], 14:12:47 INFO - 'tests': ['damp', 'tps']}, 14:12:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:47 INFO - 'tests': ['damp', 'tps']}, 14:12:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:12:47 INFO - 'tests': ['dromaeo_dom']}, 14:12:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:12:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:12:47 INFO - 'tests': ['basic_compositor_video']}, 14:12:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:12:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:12:47 INFO - 'tests': ['a11yr', 14:12:47 INFO - 'ts_paint', 14:12:47 INFO - 'tpaint', 14:12:47 INFO - 'sessionrestore', 14:12:47 INFO - 'sessionrestore_no_auto_restore', 14:12:47 INFO - 'tabpaint']}, 14:12:47 INFO - 'other-e10s': {'tests': ['a11yr', 14:12:47 INFO - 'ts_paint', 14:12:47 INFO - 'tpaint', 14:12:47 INFO - 'sessionrestore', 14:12:47 INFO - 'sessionrestore_no_auto_restore', 14:12:47 INFO - 'tabpaint']}, 14:12:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:12:47 INFO - 'ts_paint', 14:12:47 INFO - 'tpaint', 14:12:47 INFO - 'sessionrestore', 14:12:47 INFO - 'sessionrestore_no_auto_restore', 14:12:47 INFO - 'tabpaint']}, 14:12:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:12:47 INFO - 'ts_paint', 14:12:47 INFO - 'tpaint', 14:12:47 INFO - 'sessionrestore', 14:12:47 INFO - 'sessionrestore_no_auto_restore', 14:12:47 INFO - 'tabpaint']}, 14:12:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:12:47 INFO - 'tests': ['a11yr', 14:12:47 INFO - 'ts_paint', 14:12:47 INFO - 'tpaint', 14:12:47 INFO - 'sessionrestore', 14:12:47 INFO - 'sessionrestore_no_auto_restore', 14:12:47 INFO - 'tabpaint']}, 14:12:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:12:47 INFO - 'tests': ['a11yr', 14:12:47 INFO - 'ts_paint', 14:12:47 INFO - 'tpaint', 14:12:47 INFO - 'sessionrestore', 14:12:47 INFO - 'sessionrestore_no_auto_restore', 14:12:47 INFO - 'tabpaint']}, 14:12:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:12:47 INFO - 'tests': ['tsvgx', 14:12:47 INFO - 'tsvgr_opacity', 14:12:47 INFO - 'tart', 14:12:47 INFO - 'tscrollx', 14:12:47 INFO - 'cart']}, 14:12:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:12:47 INFO - 'tsvgr_opacity', 14:12:47 INFO - 'tart', 14:12:47 INFO - 'tscrollx', 14:12:47 INFO - 'cart']}, 14:12:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:47 INFO - 'talos_options': ['--disable-e10s'], 14:12:47 INFO - 'tests': ['tp5o']}, 14:12:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:47 INFO - 'tests': ['tp5o']}, 14:12:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:47 INFO - 'talos_options': ['--disable-e10s', 14:12:47 INFO - '--xperf_path', 14:12:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:12:47 INFO - 'tests': ['tp5n']}, 14:12:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:47 INFO - 'talos_options': ['--xperf_path', 14:12:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:12:47 INFO - 'tests': ['tp5n']}}, 14:12:47 INFO - 'talos.zip': {'path': '', 14:12:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:12:47 INFO - Running post-action listener: _resource_record_post_action 14:12:47 INFO - [mozharness: 2016-11-14 22:12:47.380539Z] Finished populate-webroot step (success) 14:12:47 INFO - [mozharness: 2016-11-14 22:12:47.380674Z] Running create-virtualenv step. 14:12:47 INFO - Running pre-action listener: _resource_record_pre_action 14:12:47 INFO - Running main action method: create_virtualenv 14:12:47 INFO - Creating virtualenv /builds/slave/test/build/venv 14:12:47 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 14:12:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:12:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:12:47 INFO - Using real prefix '/tools/python27' 14:12:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:12:48 INFO - Installing distribute.............................................................................................................................................................................................done. 14:12:51 INFO - Installing pip.................done. 14:12:51 INFO - Return code: 0 14:12:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:12:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:12:51 INFO - Reading from file tmpfile_stdout 14:12:51 INFO - Output received: 14:12:51 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) 14:12:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:12:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:51 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 0x10ba58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee69ca7260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b86cd30>, '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 0x10b692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b864b20>, '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.ioL8MabQ4N/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.cMGegJizu2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 14:12:51 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 14:12:51 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 14:12:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ioL8MabQ4N/Render', 14:12:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:51 INFO - 'HOME': '/Users/cltbld', 14:12:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:51 INFO - 'LOGNAME': 'cltbld', 14:12:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:51 INFO - 'MOZ_NO_REMOTE': '1', 14:12:51 INFO - 'NO_EM_RESTART': '1', 14:12:51 INFO - 'PAGER': '/bin/cat', 14:12:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:51 INFO - 'PWD': '/builds/slave/test', 14:12:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:51 INFO - 'SHELL': '/bin/bash', 14:12:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cMGegJizu2/Listeners', 14:12:51 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 14:12:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:51 INFO - 'USER': 'cltbld', 14:12:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:51 INFO - 'XPC_FLAGS': '0x0', 14:12:51 INFO - 'XPC_SERVICE_NAME': '0', 14:12:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:51 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 14:12:51 INFO - Cleaning up... 14:12:51 INFO - Return code: 0 14:12:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:12:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:51 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 0x10ba58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee69ca7260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b86cd30>, '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 0x10b692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b864b20>, '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.ioL8MabQ4N/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.cMGegJizu2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 14:12:51 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 14:12:51 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 14:12:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ioL8MabQ4N/Render', 14:12:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:51 INFO - 'HOME': '/Users/cltbld', 14:12:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:51 INFO - 'LOGNAME': 'cltbld', 14:12:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:51 INFO - 'MOZ_NO_REMOTE': '1', 14:12:51 INFO - 'NO_EM_RESTART': '1', 14:12:51 INFO - 'PAGER': '/bin/cat', 14:12:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:51 INFO - 'PWD': '/builds/slave/test', 14:12:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:51 INFO - 'SHELL': '/bin/bash', 14:12:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cMGegJizu2/Listeners', 14:12:51 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 14:12:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:51 INFO - 'USER': 'cltbld', 14:12:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:51 INFO - 'XPC_FLAGS': '0x0', 14:12:51 INFO - 'XPC_SERVICE_NAME': '0', 14:12:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:51 INFO - Downloading/unpacking psutil>=3.1.1 14:12:51 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 14:12:51 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 14:12:51 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 14:12:51 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 14:12:51 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 14:12:51 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 14:12:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:12:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:12:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:12:55 INFO - Installing collected packages: psutil 14:12:55 INFO - Running setup.py install for psutil 14:12:55 INFO - building 'psutil._psutil_osx' extension 14:12:55 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 14:12:55 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 14:12:55 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 14:12:56 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 14:12:56 INFO - building 'psutil._psutil_posix' extension 14:12:56 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 14:12:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:12:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:12:56 INFO - ^ 14:12:56 INFO - 1 warning generated. 14:12:56 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 14:12:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:12:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:12:56 INFO - Successfully installed psutil 14:12:56 INFO - Cleaning up... 14:12:56 INFO - Return code: 0 14:12:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:56 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 0x10ba58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee69ca7260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b86cd30>, '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 0x10b692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b864b20>, '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.ioL8MabQ4N/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.cMGegJizu2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 14:12:56 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 14:12:56 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 14:12:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ioL8MabQ4N/Render', 14:12:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:56 INFO - 'HOME': '/Users/cltbld', 14:12:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:56 INFO - 'LOGNAME': 'cltbld', 14:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:56 INFO - 'MOZ_NO_REMOTE': '1', 14:12:56 INFO - 'NO_EM_RESTART': '1', 14:12:56 INFO - 'PAGER': '/bin/cat', 14:12:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:56 INFO - 'PWD': '/builds/slave/test', 14:12:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:56 INFO - 'SHELL': '/bin/bash', 14:12:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cMGegJizu2/Listeners', 14:12:56 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 14:12:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:56 INFO - 'USER': 'cltbld', 14:12:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:56 INFO - 'XPC_FLAGS': '0x0', 14:12:56 INFO - 'XPC_SERVICE_NAME': '0', 14:12:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:12:56 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 14:12:56 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 14:12:56 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 14:12:56 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 14:12:56 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 14:12:56 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 14:12:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:12:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:12:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:12:59 INFO - Installing collected packages: mozsystemmonitor 14:12:59 INFO - Running setup.py install for mozsystemmonitor 14:12:59 INFO - Successfully installed mozsystemmonitor 14:12:59 INFO - Cleaning up... 14:12:59 INFO - Return code: 0 14:12:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:12:59 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')]} 14:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:59 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')]} 14:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:59 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 0x10ba58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee69ca7260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b86cd30>, '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 0x10b692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b864b20>, '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.ioL8MabQ4N/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.cMGegJizu2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 14:12:59 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 14:12:59 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 14:12:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ioL8MabQ4N/Render', 14:12:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:59 INFO - 'HOME': '/Users/cltbld', 14:12:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:59 INFO - 'LOGNAME': 'cltbld', 14:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:59 INFO - 'MOZ_NO_REMOTE': '1', 14:12:59 INFO - 'NO_EM_RESTART': '1', 14:12:59 INFO - 'PAGER': '/bin/cat', 14:12:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:59 INFO - 'PWD': '/builds/slave/test', 14:12:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:59 INFO - 'SHELL': '/bin/bash', 14:12:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cMGegJizu2/Listeners', 14:12:59 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 14:12:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:59 INFO - 'USER': 'cltbld', 14:12:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:59 INFO - 'XPC_FLAGS': '0x0', 14:12:59 INFO - 'XPC_SERVICE_NAME': '0', 14:12:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:59 INFO - Downloading/unpacking jsonschema==2.5.1 14:12:59 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 14:12:59 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 14:12:59 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 14:12:59 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 14:12:59 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 14:12:59 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 14:13:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:13:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:13:02 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 14:13:02 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 14:13:02 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 14:13:02 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 14:13:02 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 14:13:02 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 14:13:02 INFO - Downloading functools32-3.2.3-2.tar.gz 14:13:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:13:02 INFO - warning: no files found matching '*.txt' 14:13:02 INFO - no previously-included directories found matching 'build' 14:13:02 INFO - no previously-included directories found matching 'dist' 14:13:02 INFO - no previously-included directories found matching '.git*' 14:13:02 INFO - Installing collected packages: jsonschema, functools32 14:13:02 INFO - Running setup.py install for functools32 14:13:02 INFO - warning: no files found matching '*.txt' 14:13:02 INFO - no previously-included directories found matching 'build' 14:13:02 INFO - no previously-included directories found matching 'dist' 14:13:02 INFO - no previously-included directories found matching '.git*' 14:13:02 INFO - Successfully installed jsonschema functools32 14:13:02 INFO - Cleaning up... 14:13:02 INFO - Return code: 0 14:13:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:02 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 0x10ba58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee69ca7260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b86cd30>, '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 0x10b692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b864b20>, '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.ioL8MabQ4N/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.cMGegJizu2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 14:13:02 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 14:13:02 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 14:13:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ioL8MabQ4N/Render', 14:13:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:02 INFO - 'HOME': '/Users/cltbld', 14:13:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:02 INFO - 'LOGNAME': 'cltbld', 14:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:02 INFO - 'MOZ_NO_REMOTE': '1', 14:13:02 INFO - 'NO_EM_RESTART': '1', 14:13:02 INFO - 'PAGER': '/bin/cat', 14:13:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:02 INFO - 'PWD': '/builds/slave/test', 14:13:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:02 INFO - 'SHELL': '/bin/bash', 14:13:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cMGegJizu2/Listeners', 14:13:02 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 14:13:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:02 INFO - 'USER': 'cltbld', 14:13:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:02 INFO - 'XPC_FLAGS': '0x0', 14:13:02 INFO - 'XPC_SERVICE_NAME': '0', 14:13:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:13:03 INFO - Cleaning up... 14:13:03 INFO - Return code: 0 14:13:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ba58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee69ca7260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b86cd30>, '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 0x10b692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b864b20>, '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.ioL8MabQ4N/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.cMGegJizu2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 14:13:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:13:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:13:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ioL8MabQ4N/Render', 14:13:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:03 INFO - 'HOME': '/Users/cltbld', 14:13:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:03 INFO - 'LOGNAME': 'cltbld', 14:13:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:03 INFO - 'MOZ_NO_REMOTE': '1', 14:13:03 INFO - 'NO_EM_RESTART': '1', 14:13:03 INFO - 'PAGER': '/bin/cat', 14:13:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:03 INFO - 'PWD': '/builds/slave/test', 14:13:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:03 INFO - 'SHELL': '/bin/bash', 14:13:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cMGegJizu2/Listeners', 14:13:03 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 14:13:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:03 INFO - 'USER': 'cltbld', 14:13:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:03 INFO - 'XPC_FLAGS': '0x0', 14:13:03 INFO - 'XPC_SERVICE_NAME': '0', 14:13:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:03 INFO - Downloading/unpacking blobuploader==1.2.4 14:13:03 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 14:13:03 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 14:13:03 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 14:13:03 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 14:13:03 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 14:13:03 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 14:13:05 INFO - Downloading blobuploader-1.2.4.tar.gz 14:13:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:13:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:13: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 14:13: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 14:13: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 14:13: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 14:13: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 14:13: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 14:13:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:13:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:13: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 14:13: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 14:13: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 14:13: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 14:13: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 14:13: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 14:13:07 INFO - Downloading docopt-0.6.1.tar.gz 14:13:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:13:07 INFO - Installing collected packages: blobuploader, requests, docopt 14:13:07 INFO - Running setup.py install for blobuploader 14:13:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:13:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:13:07 INFO - Running setup.py install for requests 14:13:07 INFO - Running setup.py install for docopt 14:13:08 INFO - Successfully installed blobuploader requests docopt 14:13:08 INFO - Cleaning up... 14:13:08 INFO - Return code: 0 14:13:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:13:08 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')]} 14:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:08 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')]} 14:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:08 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 0x10ba58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee69ca7260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b86cd30>, '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 0x10b692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b864b20>, '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.ioL8MabQ4N/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.cMGegJizu2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 14:13:08 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 14:13:08 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 14:13:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ioL8MabQ4N/Render', 14:13:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:08 INFO - 'HOME': '/Users/cltbld', 14:13:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:08 INFO - 'LOGNAME': 'cltbld', 14:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:08 INFO - 'MOZ_NO_REMOTE': '1', 14:13:08 INFO - 'NO_EM_RESTART': '1', 14:13:08 INFO - 'PAGER': '/bin/cat', 14:13:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:08 INFO - 'PWD': '/builds/slave/test', 14:13:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:08 INFO - 'SHELL': '/bin/bash', 14:13:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cMGegJizu2/Listeners', 14:13:08 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 14:13:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:08 INFO - 'USER': 'cltbld', 14:13:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:08 INFO - 'XPC_FLAGS': '0x0', 14:13:08 INFO - 'XPC_SERVICE_NAME': '0', 14:13:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:13:08 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-VBf0Ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:13:08 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-Qiozpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:13:08 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-ZhYmfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:13:08 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-UxiLt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:13:08 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-VYx2Sz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:13:08 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-TPSlXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:13:09 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-HyMxdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:13:09 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-Mb9C9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:13:09 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-Ybu5nu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:13:09 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-w9NWXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:13:09 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-lEPRLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:13:09 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-f4HlQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:13:09 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-diCo28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:13:09 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-ervquv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:09 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-CUReTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:13:09 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-62UWdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:13:10 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-lACZvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:13:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:13:10 INFO - Running setup.py install for manifestparser 14:13:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:13:10 INFO - Running setup.py install for mozcrash 14:13:10 INFO - Running setup.py install for mozdebug 14:13:10 INFO - Running setup.py install for mozdevice 14:13:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:13:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:13:10 INFO - Running setup.py install for mozfile 14:13:10 INFO - Running setup.py install for mozhttpd 14:13:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:13:11 INFO - Running setup.py install for mozinfo 14:13:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:13:11 INFO - Running setup.py install for mozInstall 14:13:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:13:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:13:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:13:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:13:11 INFO - Running setup.py install for mozleak 14:13:11 INFO - Running setup.py install for mozlog 14:13:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:13:11 INFO - Running setup.py install for moznetwork 14:13:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:13:11 INFO - Running setup.py install for mozprocess 14:13:11 INFO - Running setup.py install for mozprofile 14:13:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:13:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:13:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:13:12 INFO - Running setup.py install for mozrunner 14:13:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:13:12 INFO - Running setup.py install for mozscreenshot 14:13:12 INFO - Running setup.py install for moztest 14:13:12 INFO - Running setup.py install for mozversion 14:13:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:13:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:13:12 INFO - Cleaning up... 14:13:12 INFO - Return code: 0 14:13:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:13:12 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')]} 14:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:12 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')]} 14:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:12 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 0x10ba58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee69ca7260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b86cd30>, '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 0x10b692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b864b20>, '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.ioL8MabQ4N/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.cMGegJizu2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 14:13:12 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 14:13:12 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 14:13:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ioL8MabQ4N/Render', 14:13:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:12 INFO - 'HOME': '/Users/cltbld', 14:13:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:12 INFO - 'LOGNAME': 'cltbld', 14:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:12 INFO - 'MOZ_NO_REMOTE': '1', 14:13:12 INFO - 'NO_EM_RESTART': '1', 14:13:12 INFO - 'PAGER': '/bin/cat', 14:13:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:12 INFO - 'PWD': '/builds/slave/test', 14:13:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:12 INFO - 'SHELL': '/bin/bash', 14:13:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cMGegJizu2/Listeners', 14:13:12 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 14:13:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:12 INFO - 'USER': 'cltbld', 14:13:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:12 INFO - 'XPC_FLAGS': '0x0', 14:13:12 INFO - 'XPC_SERVICE_NAME': '0', 14:13:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:13:13 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-3uXRRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:13:13 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)) 14:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:13:13 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-bJmmqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:13:13 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)) 14:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:13:13 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-kZuYr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:13:13 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)) 14:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:13:13 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-8J5zty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:13:13 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)) 14:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:13:13 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-HYQiQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:13:13 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)) 14:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:13:13 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-nlX3Yx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:13:13 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)) 14:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:13:13 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-bFi4fI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:13:13 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)) 14:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:13:13 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-ctkZ9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:13:13 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)) 14:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:13:13 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-xnluDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:13:13 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)) 14:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:13:13 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-eDDyPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:13:14 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)) 14:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:13:14 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-V_jGwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:13:14 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)) 14:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:13:14 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-TTLeex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:13:14 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)) 14:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:13:14 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-lvbJdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:13:14 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)) 14:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:13:14 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-FLy_Ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:13:14 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)) 14:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:14 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-sZB30B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:14 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)) 14:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:13:14 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-iSIo_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:13:14 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)) 14:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:13:14 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-Pefw3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:13:14 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)) 14:13:14 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)) 14:13:14 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)) 14:13:14 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)) 14:13:14 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)) 14:13:14 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)) 14:13:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:17 INFO - Downloading blessings-1.6.tar.gz 14:13:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:13:17 INFO - Installing collected packages: blessings 14:13:17 INFO - Running setup.py install for blessings 14:13:17 INFO - Successfully installed blessings 14:13:17 INFO - Cleaning up... 14:13:17 INFO - Return code: 0 14:13:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:13:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:13:18 INFO - Reading from file tmpfile_stdout 14:13:18 INFO - Current package versions: 14:13:18 INFO - blessings == 1.6 14:13:18 INFO - blobuploader == 1.2.4 14:13:18 INFO - docopt == 0.6.1 14:13:18 INFO - functools32 == 3.2.3-2 14:13:18 INFO - jsonschema == 2.5.1 14:13:18 INFO - manifestparser == 1.1 14:13:18 INFO - mozInstall == 1.12 14:13:18 INFO - mozcrash == 1.0 14:13:18 INFO - mozdebug == 0.1 14:13:18 INFO - mozdevice == 0.48 14:13:18 INFO - mozfile == 1.2 14:13:18 INFO - mozhttpd == 0.7 14:13:18 INFO - mozinfo == 0.9 14:13:18 INFO - mozleak == 0.1 14:13:18 INFO - mozlog == 3.4 14:13:18 INFO - moznetwork == 0.27 14:13:18 INFO - mozprocess == 0.23 14:13:18 INFO - mozprofile == 0.28 14:13:18 INFO - mozrunner == 6.13 14:13:18 INFO - mozscreenshot == 0.1 14:13:18 INFO - mozsystemmonitor == 0.3 14:13:18 INFO - moztest == 0.7 14:13:18 INFO - mozversion == 1.4 14:13:18 INFO - psutil == 3.1.1 14:13:18 INFO - requests == 1.2.3 14:13:18 INFO - wsgiref == 0.1.2 14:13:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:13: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')]} 14:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13: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')]} 14:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:18 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 0x10ba58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee69ca7260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b86cd30>, '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 0x10b692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b864b20>, '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.ioL8MabQ4N/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.cMGegJizu2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 14:13:18 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 14:13:18 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 14:13:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ioL8MabQ4N/Render', 14:13:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:18 INFO - 'HOME': '/Users/cltbld', 14:13:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:18 INFO - 'LOGNAME': 'cltbld', 14:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:18 INFO - 'MOZ_NO_REMOTE': '1', 14:13:18 INFO - 'NO_EM_RESTART': '1', 14:13:18 INFO - 'PAGER': '/bin/cat', 14:13:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:18 INFO - 'PWD': '/builds/slave/test', 14:13:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:18 INFO - 'SHELL': '/bin/bash', 14:13:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cMGegJizu2/Listeners', 14:13:18 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 14:13:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:18 INFO - 'USER': 'cltbld', 14:13:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:18 INFO - 'XPC_FLAGS': '0x0', 14:13:18 INFO - 'XPC_SERVICE_NAME': '0', 14:13:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:18 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)) 14:13:18 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)) 14:13:18 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)) 14:13:18 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)) 14:13:18 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)) 14:13:18 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)) 14:13:18 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)) 14:13:18 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)) 14:13:18 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)) 14:13:18 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)) 14:13:18 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)) 14:13:18 INFO - Cleaning up... 14:13:18 INFO - Return code: 0 14:13:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:13: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')]} 14:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13: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')]} 14:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ba58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee69ca7260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b86cd30>, '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 0x10b692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b864b20>, '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.ioL8MabQ4N/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.cMGegJizu2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 14:13: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', 'jsonschema'] in /builds/slave/test/build 14:13: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 jsonschema 14:13:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ioL8MabQ4N/Render', 14:13:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:18 INFO - 'HOME': '/Users/cltbld', 14:13:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:18 INFO - 'LOGNAME': 'cltbld', 14:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:18 INFO - 'MOZ_NO_REMOTE': '1', 14:13:18 INFO - 'NO_EM_RESTART': '1', 14:13:18 INFO - 'PAGER': '/bin/cat', 14:13:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:18 INFO - 'PWD': '/builds/slave/test', 14:13:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:18 INFO - 'SHELL': '/bin/bash', 14:13:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cMGegJizu2/Listeners', 14:13:18 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 14:13:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:18 INFO - 'USER': 'cltbld', 14:13:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:18 INFO - 'XPC_FLAGS': '0x0', 14:13:18 INFO - 'XPC_SERVICE_NAME': '0', 14:13:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:13:18 INFO - Cleaning up... 14:13:18 INFO - Return code: 0 14:13:18 INFO - Running post-action listener: _resource_record_post_action 14:13:18 INFO - Running post-action listener: _start_resource_monitoring 14:13:18 INFO - Starting resource monitoring. 14:13:18 INFO - [mozharness: 2016-11-14 22:13:18.625844Z] Finished create-virtualenv step (success) 14:13:18 INFO - [mozharness: 2016-11-14 22:13:18.626873Z] Running install step. 14:13:18 INFO - Running pre-action listener: _resource_record_pre_action 14:13:18 INFO - Running main action method: install 14:13:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:13:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:13:18 INFO - Reading from file tmpfile_stdout 14:13:18 INFO - Detecting whether we're running mozinstall >=1.0... 14:13:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:13:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:13:18 INFO - Reading from file tmpfile_stdout 14:13:18 INFO - Output received: 14:13:18 INFO - Usage: mozinstall [options] installer 14:13:18 INFO - Options: 14:13:18 INFO - -h, --help show this help message and exit 14:13:18 INFO - -d DEST, --destination=DEST 14:13:18 INFO - Directory to install application into. [default: 14:13:18 INFO - "/builds/slave/test"] 14:13:18 INFO - --app=APP Application being installed. [default: firefox] 14:13:18 INFO - mkdir: /builds/slave/test/build/application 14:13:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:13:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:13:52 INFO - Reading from file tmpfile_stdout 14:13:52 INFO - Output received: 14:13:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:13:52 INFO - Running post-action listener: _resource_record_post_action 14:13:52 INFO - [mozharness: 2016-11-14 22:13:52.469097Z] Finished install step (success) 14:13:52 INFO - [mozharness: 2016-11-14 22:13:52.469232Z] Running run-tests step. 14:13:52 INFO - Running pre-action listener: _resource_record_pre_action 14:13:52 INFO - Running main action method: run_tests 14:13:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:13:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:13:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:13:52 INFO - 2016-11-14 14:13:52.483 screenresolution[1982:13440] starting screenresolution argv=screenresolution get 14:13:52 INFO - 2016-11-14 14:13:52.486 screenresolution[1982:13440] Display 0: 1600x1200x32@60 14:13:52 INFO - 2016-11-14 14:13:52.492 screenresolution[1983:13442] starting screenresolution argv=screenresolution list 14:13:52 INFO - Available Modes on Display 0 14:13:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:13:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:13:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:13:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:13:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:13:52 INFO - Intel Iris: 14:13:52 INFO - Chipset Model: Intel Iris 14:13:52 INFO - Type: GPU 14:13:52 INFO - Bus: Built-In 14:13:52 INFO - VRAM (Dynamic, Max): 1536 MB 14:13:52 INFO - Vendor: Intel (0x8086) 14:13:52 INFO - Device ID: 0x0a2e 14:13:52 INFO - Revision ID: 0x0009 14:13:52 INFO - Displays: 14:13:52 INFO - Display: 14:13:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:13:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:13:52 INFO - Display Serial Number: 1600x1200 60 14:13:52 INFO - Main Display: Yes 14:13:52 INFO - Mirror: Off 14:13:52 INFO - Online: Yes 14:13:52 INFO - Rotation: Supported 14:13:52 INFO - Return code: 0 14:13:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:13:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:13:52 INFO - Python 2.7.3 14:13:52 INFO - Return code: 0 14:13:52 INFO - grabbing minidump binary from tooltool 14:13:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:52 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 0x10b86cd30>, '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 0x10b692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b864b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:13:52 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 14:13:52 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 14:13:52 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 14:13:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:13:52 INFO - Return code: 0 14:13:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:13:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:13:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:13:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:13:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:13:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0136', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:13:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0136 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:13:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ioL8MabQ4N/Render', 14:13:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:52 INFO - 'HOME': '/Users/cltbld', 14:13:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:52 INFO - 'LOGNAME': 'cltbld', 14:13:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:13:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:13:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:52 INFO - 'MOZ_NO_REMOTE': '1', 14:13:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:13:52 INFO - 'NO_EM_RESTART': '1', 14:13:52 INFO - 'PAGER': '/bin/cat', 14:13:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:52 INFO - 'PWD': '/builds/slave/test', 14:13:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:13:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:52 INFO - 'SHELL': '/bin/bash', 14:13:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cMGegJizu2/Listeners', 14:13:52 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 14:13:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:52 INFO - 'USER': 'cltbld', 14:13:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:52 INFO - 'XPC_FLAGS': '0x0', 14:13:52 INFO - 'XPC_SERVICE_NAME': '0', 14:13:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0136', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:13:53 INFO - mozversion application_buildid: 20161114112842 14:13:53 INFO - mozversion application_changeset: 3f2202420b01fcc253118f5dcc07f1be9b3c3009 14:13:53 INFO - mozversion application_display_name: Nightly 14:13:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:13:53 INFO - mozversion application_name: Firefox 14:13:53 INFO - mozversion application_remotingname: firefox 14:13:53 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 14:13:53 INFO - mozversion application_vendor: Mozilla 14:13:53 INFO - mozversion application_version: 52.0a1 14:13:53 INFO - mozversion platform_buildid: 20161114112842 14:13:53 INFO - mozversion platform_changeset: 3f2202420b01fcc253118f5dcc07f1be9b3c3009 14:13:53 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 14:13:53 INFO - mozversion platform_version: 52.0a1 14:13:53 INFO - using testdate: 1479161633 14:13:53 INFO - actual date: 1479161633 14:13:53 INFO - starting webserver on 'localhost:49667' 14:13:53 INFO - SUITE-START | Running 2 tests 14:13:53 INFO - TEST-START | dromaeo_css 14:13:53 INFO - operating with platform_type : mac_ 14:13:53 INFO - Initialising browser for dromaeo_css test... 14:13:53 INFO - TEST-INFO | started process 1993 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpoXhGKW/profile http://localhost:49667/getInfo.html) 14:13:55 INFO - PROCESS | 1993 | __metrics Screen width/height:1600/1200 14:13:55 INFO - PROCESS | 1993 | colorDepth:24 14:13:55 INFO - PROCESS | 1993 | Browser inner width/height: 1024/689 14:13:55 INFO - PROCESS | 1993 | __metrics 14:13:55 INFO - PROCESS | 1993 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:13:55 INFO - PROCESS | 1993 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:13:56 INFO - TEST-INFO | 1993: exit 0 14:13:56 INFO - Browser initialized. 14:13:56 INFO - Running cycle 1/1 for dromaeo_css test... 14:13:56 INFO - TEST-INFO | started process 1995 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpoXhGKW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:14:08 INFO - PROCESS | 1995 | RSS: Main: 232443904 14:14:08 INFO - PROCESS | 1995 | 14:15:00 INFO - PROCESS | 1995 | 1479161700098 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] 14:17:13 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:49667/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49667/tests/dromaeo/cssquery-ext.html) 14:17:15 INFO - PROCESS | 1995 | RSS: Main: 380801024 14:17:15 INFO - PROCESS | 1995 | 14:20:18 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:49667/tests/dromaeo/cssquery-ext.html (next: http://localhost:49667/tests/dromaeo/cssquery-jquery.html) 14:20:18 INFO - PROCESS | 1995 | RSS: Main: 369303552 14:20:18 INFO - PROCESS | 1995 | 14:23:21 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:49667/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49667/tests/dromaeo/cssquery-mootools.html) 14:23:21 INFO - PROCESS | 1995 | RSS: Main: 352391168 14:23:21 INFO - PROCESS | 1995 | 14:26:24 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:49667/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49667/tests/dromaeo/cssquery-prototype.html) 14:26:25 INFO - PROCESS | 1995 | RSS: Main: 358064128 14:26:25 INFO - PROCESS | 1995 | 14:29:28 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:49667/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49667/tests/dromaeo/cssquery-yui.html) 14:29:29 INFO - PROCESS | 1995 | RSS: Main: 336674816 14:29:29 INFO - PROCESS | 1995 | 14:32:32 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:49667/tests/dromaeo/cssquery-yui.html (next: http://localhost:49667/tests/dromaeo/cssquery-dojo.html) 14:32:32 INFO - PROCESS | 1995 | RSS: Main: 458141696 14:32:32 INFO - PROCESS | 1995 | 14:32:32 INFO - PROCESS | 1995 | __start_tp_report 14:32:32 INFO - PROCESS | 1995 | _x_x_mozilla_page_load 14:32:32 INFO - PROCESS | 1995 | _x_x_mozilla_page_load_details 14:32:32 INFO - PROCESS | 1995 | |i|pagename|runs| 14:32:32 INFO - PROCESS | 1995 | |0;dojo.html;2686.3821321096148;3223.7582181336406;3263.0747858763325;3277.3445310937786;3299.356625458035;5511.063119269724;5521.585881058916;5571.303587051619;5574.8606284842845;5626;5530;5555.138953462219;5626.465485778855;5638.689872057045;5639.097744360903;5018.573421259197;5074.898502029957;5091.516305950947;5126.128558145124;5141.922871156936;5098.158803797369;5192.169252919533;5192.480751924815;5219.295395121648;5227.4511176326505;5194.220866869962;5197.7920883164625;5222.242774797652;5252.842414727565;5259.421463639016;5063.291139240472;5079.492050794928;5128.8461346159675;5139.229115632685;5145.33110695611;5142.177251639757;5142.30578871855;5161.922571161435;5196.974015129938;5214.713190774506;5603.943960560383;5610.4670056344585;5612.831615051555;5619.971900140514;5665.1502274658715;5866.208061911672;5956.7319470623925;5964.761409543613;6095.603785753912;6109.327973922865;4328.761918094504;6117.309816958451;6119.289302195927;6124.564025909317;6135.815925522197;5641.661500309994;5646.802361917355;5654.349749778746;5662.745176467069;5662.886742265132;6175.598586091979;6197.163382943335;6222.12890195364;6250.718717657716;6319.178506794087;5985.7904973326595;5997.730102145414;6009.338972712998;6016.849578760479;6035.336113027652;6152.292486364126;6155.4767844732805;6181.567290289637;6188.597741146811;6194;5912.40875912414;5969.582129250911;5986.07215881539;5997.190379298826;6062.939370606237;6117.9187529241;6127.877442451215;6131.327140476762;6131.743695907034;6137.113808755727;4834.975825120922;4849.563539281482;4858.95141048585;4882.048000639841;4890.95109048912;4296.699231053859;4298.720583162084;4323.373110898965;4338.154059958341;4362.323839804835;5638.464345887134;5646.632968857011;5656.547476201894;5658.009848276617;5658.405867383948;6107.450329470369;6117.388261173847;6119.204503414617;6131.264248290234;6138.447539721446;5555.944440555542;5611.663300202001;5630.183623374588;5638.85902852432;5653.406392328829;5129.9743501283;5267.2889159962915;5285.497877701612;5289.338832645919;5291.074062039204;6209.813705588839;6231.968840155861;6238.532110091852;6256.687165641791;6256.968715156395;5378.513092925929;6087.619726819819;6119.380693981147;6182.876342473215;6198.163247961648;6318.715657795502;6344.619322840645;6349.428551430394;6350.1109844622715;6352.936470635234;5869.7945571904775;6067.484263837623;6073.939260607514;6074.058520929263;6092.603980741238;5983.072623743326;6029.276486821609;6039.335673076046;6047.244094488189;6107.5226075721885;4378.605925466723;5886.646801191942;5940.465358117617;5944.494717949023;5957.523398128226;5823.7670493181495;5829.067349224104;5829.562782791392;5836.737346819318;5843.824685259449;5652.491275785199;5684.857878553069;5690.459406356389;5699.202111704281;5740.13897915316;5876.647401155944;5878.735456904364;5905;5913.763449461973;5950.7917222896995;5692.573057020657;5694.259746232963;5734.139879018181;5749.770009199585;5812.912806307993 14:32:32 INFO - PROCESS | 1995 | |1;ext.html;468.53380886018414;483.7411984588244;504.57867680486794;513.6378852908698;569.1548051144053;37120.70077547286;39409.211815763665;39414.40878386827;39421.01447463815;39464.80267598662;39872.003199920015;40197.59802401975;40226.195476090536;40286.59713402865;40327.395089073616;9063.95468022659;9300.534973251344;9302.86045709315;9303.116203960646;9303.348765586412;9340.766480838;9442.244620430383;9456.243500519942;9460.905390946105;9466.053394660548;9425.528723563828;9478.478683672396;9479.47862867542;9487.28845336599;9493.05069493052;9350.672726454575;9417.717468475958;9418.764530886714;9437.575309111107;9442.622295108222;9197;9200.723978280595;9211.355205135644;9244.815103697889;9315.72052838416;38505;38814.61185388138;38884.22231555353;38885.80557097225;38927;3206.8236247006407;3352.547406100194;3394.8132852693093;3597.80212088335;3924.921501569981;24099.518009639884;29228.853855730584;30285.69714302851;30547.098025687523;31093.689063109305;3197.9200519986953;3231.87072517099;3235.142687187897;3257.1531401835455;3315.834208289576;356463.2176839125;360668;362187;362313.37686623324;363225;56639.71680141573;56710.865782684115;57229.85540289254;57395;57402.425975740545;50728.492715072374;61183;61184;61421;61463.692681536304;14765.77851332231;14993.925030374778;15019.474318398803;15054.397824086915;15092.245387730569;11547.364894930692;11567.30596164216;11571.190016698918;11591.304521728724;11613.30320180792;13416.664583385298;16443.260053297632;16560.337586496404;16574.75137872933;16693.913260842393;6525.249596296487;6548.0384367457955;6648.800535983928;6658.766943156967;6695.230048544382;674.4711195626384;859.2352806002663;878.4465786554557;995.3629677006721;1024.078329503438;28050.2987425316;28703.995360162295;29258.853705731333;29376.971805987116;30221;847.6990668312784;1025.092792878308;1073.6564299424265;1076.5101878645198;1090.596479302663;14179.291035448186;14671.046381985137;14710;15165.772513412308;15174.696506069817;631452.9555578964;635212;636018;637485;637667;54195.18707219395;54556.18165727517;54561.181582276295;54658.45341546613;54725.45274547204;1320.18148747777;1345.2937207965897;1356.9796453053211;1361;1367.897407694407;27562.310942226606;28415.289617758895;28800.42399152047;28948;28953.13140605785;45618.77190614026;48334.03331933412;48845.02309953852;48876.02247955092;48912.75543622259;525686.3166630879;585133;586060;586406.0679696574;587417;37537.624623754506;38297.61702382941;38624;38769.61230387773;38807.22385552217;44023.89940251519;44481.77759111184;44812.551874480836;45617.31574026326;45682;37278.44082338821;37465.625343747306;37890.8105459471;38622.42066369063;38743.8376848695;30825.22936926505;37258.25483490261;38439.61560384472;38461.423078653286;39642.80178599089;36494.4525832118;36991.81504092462;37005.81497092497;37036.25927481381;37269.627303726615;28996.27509312284;29302.70697293085;32001.67998319987;33481.99554013384;33759.83120084384;24723.505529889662;24739.505209895342;25015.87492062528;25363.36591585225;25448.491030178924 14:32:32 INFO - PROCESS | 1995 | |2;jquery.html;346.9375512407769;370.60160327647776;371.5597017534222;382.2851268128599;398.97406668566543;14760.77858832117;14963.102213867161;15784.842151578467;19645.607087858236;20401.48996275094;17870.910645446755;18806.34177803774;18845;18899;19080.61838763224;5580.804671836487;5940.88118237635;5958.195643588104;5961.612495187821;5963.970180149093;5279.70961597112;5292.782659988205;5829.795957141501;5884.999550076497;5889.7644094236175;5682.488576028157;5701.230333904932;5840.707964601753;5847.883042339172;5852.3562408135285;4252.510961239466;5321.3348331458565;5506.669599823983;5806.59353845231;5914.704264786744;5471.753771080311;5601.795613942981;5700.201971723962;5729.083346664514;5730.598858079936;12944.63439505726;13239.20564766117;13253.07228493996;13265.336733163304;15139.772903406458;2289.1186893046297;2290.038183962723;2305.5388922215625;2335.1359996801293;2382.4282172278704;8805.855238818913;9484.288678349083;9751.561179746928;9875.40747555149;10179.949100254451;1571.4185751272103;1807.502936692407;1867.785204701463;1871.6350311689225;1873.5409824592925;117568;119746.40126799398;124869;126588;127993;9031.81936361269;11282.379469129113;11300.830487542693;11396.829047564293;11570.94214528922;14361.353739081766;14372;14390.496332628307;14393.78409323861;14414.711705765825;7832.451728379073;8145.389095817837;8153.1439198884445;8261.339092872677;8301.29438997692;7708.190639982833;7897.960510197528;7919.930809340781;8038.959805201053;8082.878756818653;3823.311803875329;4458.878784850617;4464.17412778637;4532.592066713945;4572.314152877095;1822.8450581700438;1863.3602131827417;1901.7242499837446;1901.8193271639173;1910.646530391882;846.8391005709019;940.6707652321633;946.8248374050822;963.5326866469757;1001.5643195210107;17303.653926921645;20080.69878951817;21327.78672213258;21388.572228555342;21696;706.5230969095943;899.6536333511646;930.6510058727977;943.9339246252696;955.2787645327733;8589.742307730778;8718.346124040723;10797.83803242952;11155.609553665745;11197;577507;588042;588609;591247;592156;98894.52763680965;112362;122255;125177;134821.32589337186;960.4525420510242;971.7133445633697;984.625842179967;1007.9193664506822;1008.0876806490138;17959.01225432614;18219.726704099183;18389.72415413743;18617.813821861608;18779;35050.12374690653;41358;51113.2110376135;51188.488115119864;54932;55927.44072559222;63965.68017159885;67980.64038719297;69198.65400672964;71943;7662.885056724042;7912.802179945547;8005.9599702001115;8019.679212831421;8208.261256486945;10273.640422585473;12542.874571254171;12562.183458075197;12978.480860765463;13174.670633233862;10696.62561810333;10906.50920708554;11220.495077721358;11231.045361144394;11287.7742445149;10815.837762433412;10904.672859814218;11148.163887708291;11218.55125794959;11319;11122.332660040423;11199.608013719806;11405.486753095965;11591.478383472595;11603.419829008348;7889.289963903277;8103.230193131643;8131.471454355448;8316.542590157358;8394.202550757669;8857.911420885708;10393.740156496147;11068;11190;11270.774584508428 14:32:32 INFO - PROCESS | 1995 | |3;mootools.html;1330.5941687785235;1358.286899377826;1388.9791003612343;1440.1934916446792;1693.9745903811445;6806.795796126118;7505.174430812613;7885.605719714013;8376.58117094146;8385.706500272492;8255.463394879329;8343.415960882741;8346.707865224718;8357.707480238192;8369.20492553208;2312.09828167015;2339.7192336919593;2339.8245131615113;2343.2735851885886;2348.988255058722;2297.7012988311494;2351.423901144222;2352.070931981866;2352.988235058831;2366.420227044368;2263.162629826966;2264.094362255095;2283.4291427143216;2292.4039749665126;2292.5300313435705;2244.708187935574;2256.3343813575034;2278.384836094245;2281.4752606900506;2285.680004799321;2300.6088964875844;2319.3273950554317;2325.546518428907;2326.1160758911674;2332.696749422564;8122.715704950358;8298.83402331956;8375.623096960651;8391.664333426656;8393.328533717287;4985.750712464399;5190.792368305263;5197.532222100029;5199.350081239845;5210.6613070150315;2779.2634951737805;3173.3970545596494;3245.2049247934406;3318.502224666295;3340.448825943716;986.2159583131356;1017.8931212222684;1019.418931209211;1020.7193021918957;1022.2639699416409;10451.372917624967;10758.408287544182;10789.244752867302;10796.5681372745;10967.012968832763;4155.501339839178;4292.098659281523;4325.243082460557;4449.688521803443;4455;8089.02931648206;8104.270615644502;8105.230003149692;8106.7162649308175;8109.351251899834;4956.677815941952;4962.60299176065;4962.702237865739;4962.751862406865;4966.056449274699;3753.474513568048;3979.820908059144;4285.892852678646;4439.511653718088;4750.14497390473;3549.3256281306467;3580.820958952094;3662.853485860589;3664.1022949377575;3665.651763082503;1117.3240189685287;1122.141561705296;1124.201816710145;1129;1136.6362763915467;764.5107131435885;795.2127393880132;888.1296329596902;939.7039932421266;940.9576569054409;4193.475815523059;5363.704996225167;5701.344345400249;5823.708814559255;5899.46904778572;829.3613917283778;838.0739283092217;880.6125304865839;881.3962435731545;966.6616684160488;2901.8113822601886;3674.467202255658;3735.5517337919623;3861.227754449121;3898.1813819098306;9625.614975401046;10105.14106300958;10178;10252.23108266883;10279.897201028043;8448.493090414595;10096.596136154618;10236.846447303296;10238.488075596339;10256.282060255711;7045.330693584101;7479.214682458482;7523.510971786816;7761.805954851174;7872.803179920548;4548.56788605082;5054.721990290569;5474.288342515448;5485.232067510631;5589.888202235851;9268.212201962908;9269.397489163182;9394.436333819993;9409.952950235205;9537.380070295409;8784.648614055366;8840.838360447662;9322.347435679438;9580.23358131334;9714.611415543299;7017.719291228498;7232.819179520554;7256.600886951268;7305.890411643723;7314.780556583311;7834.608269586384;7939.6030198491;8014.83970320602;8015.11833698281;8032.277095061472;6915.654217289015;6979.232284448605;6999.965000174967;7051.858962820817;7177.031100801325;6847.041414202115;6897.62063086515;6910.654467276516;6988.510804243654;7007.229204787367;6655.068290439245;6671.066050752996;6679.966600166968;6754.358335958273;6785.1518560179975;4772.284157376422;4807.326974223542;4810.903781924412;4846.418429788448;4955.702657840541;4820.34925285082;4821;4837.56461918415;4914.0908931847725;4944.752762361795 14:32:32 INFO - PROCESS | 1995 | |4;prototype.html;263.5388070875967;303.64321921741964;312.7310512958855;317.4222914295981;319.10013761193443;4528.890421846652;5048.939722658241;5056.443791182976;5059.620528460367;5089.949100508994;4469.776511174445;4852.50096200457;4926.3842019747535;4939.901201975958;4979.626528010395;3882.941755873664;3888.969423102873;3889.4165875118674;3896.8051597420017;3942.664873485757;2901.3762041161144;3329.400707872582;3617.40312848381;3745.4381842723537;3910.9022274443223;3357.6810203030673;3593.568771747394;3721.8511259549587;3812.4281357796276;3868.9806550967205;3801.1944326444996;3811.246826459832;3832.693384529231;3842.2507611016003;3845.5962123977;2889.7110288971144;2908.956365654517;3286.4741641337273;3330.400527904976;3337.182390314364;4207.768572728499;4228.6617070634275;4434.756088415136;4858.732769697665;4868.9269660955115;3400.914977125567;4201.70588058836;4318.2227199104145;4375.628071613916;4388.824447022115;5223.242629818693;5233.921491177597;5244.737763111829;5244.763985620618;5246.265522826808;3051.3897220555978;3644.6173151819207;3655.3054919565197;3655.9946014845864;3662.212624285765;5162.638615296931;5398.838034858961;5458.61789674723;5465.453454654582;5468.453154684539;2968.881244750186;3660.798656073888;3669.7247706422127;3675.448682697563;3682.797446140434;3720.906977325534;3734.533183352081;3736.3834967230105;3753.2493501299305;3753.7372383933407;3287.3260981498734;3643.9796856880116;3989.7606143630896;4157.734034950959;4381.342798580238;5036.496350364934;5170.586353091744;5283;5289.338832645919;5337.077248222553;1005.3917379985149;1119.8376235445814;1155.0297749889965;1176.988230117705;1206.1918514595243;722.5086940880146;776.9106552746506;791.666042718022;810.2140923304386;850.2050582705191;4203;4621.237495813153;4660.883477913011;4663.370444989883;4761.571458568746;4725.692829966041;4860.538248866352;4898.020395920759;4980.9252861207115;5067.771950262247;4360.171567402183;4661.930071048937;4705.788239529229;5108.310378098983;5162.367192731734;2550.525989789901;2664.254008877517;2742.4515096980285;2766.405222877091;2772.265349682336;5050.419201791768;5294.523492885582;5361.222622719683;5373.570114390839;5471.972640136773;4294.656427485794;4888.951110488921;5110.313596513141;5547.920903898375;5565.833025009256;4885.5847252983895;4911;5590.580706447033;5591.832245032656;5594.8810237952575;5182.24857395683;5410.48600382963;5463.836084917459;5508.228847961209;5509.9806535791;4973.701577905337;5388.919166212589;5392.568594512508;5412.621116521806;5505.642133261325;5349.331333583302;5623.943760562342;5624.578156638187;5643.661380317194;5679.204911312502;3387.0346951118813;3661.5423072115987;3680.7975561344383;3694.796786176786;3700.38943574318;4730.574248317668;5423.240746295601;5441.319835020622;5448.482394172705;5461.863453413537;4842.806287748592;4859.3682821233015;4879;4968.931679688884;5288.629795914249;4784.736839473862;5032.823851165338;5053.610257179305;5291.206319052173;5317.4270127690925;4977.751112444435;5176.534111929945;5189.429162792015;5196.168613021899;5206.661566998133;4436.336373859803;5156.535911767959;5267.525922666979;5292.470752924677;5313.0247061645605;5197.272381866617;5203.765830537711;5211.983663185794;5214.947850521598;5243.292155558951 14:32:32 INFO - PROCESS | 1995 | |5;yui.html;701.7683964642052;740.8073900785794;766.3218052023959;783.6669415498412;800.2157885272435;13595.388207530661;13863.376148073336;14327;14367.425302987895;14402.927985360071;12638.241705497676;12741.808872866892;12764.297963611998;12774.233545987247;12776.041796865225;640.6252342379713;665.4111111666186;667.9599224046549;799.8800179973022;815.5881279953616;818.3126174013827;819.8401311744212;831.6922738586701;834.6160766047625;835.0021724039775;860.6213266162899;882.9381943263974;883.0904168706243;884.2086332732201;888.5557221389305;682.7780971184385;710.3819676881095;878.3061381508601;879.226280872831;880.1110878013221;627.645380360095;655.5673255651247;662.3773652766432;664.5879554676084;664.8836453620645;10932.234743567953;12920.935395323057;12929;13066.150700204456;13193.868061319456;12989;13172.53896113641;13177;13274.137181083295;13326.800097998537;12123.818142727867;12141.69645758863;12167.330796806173;12515.812262816065;12724.936375318157;4338.652907767371;4366.400547784798;4433.046894917576;4483.730976141442;4490.797914093873;81515;82560.58719706423;84947.57526212391;85989.57005214997;88262.11737882603;10455.947720261502;10467.592453735531;10469.057784799405;10486.63296784624;10888.346699198073;20195.798042019393;20198.697019544426;20213.393598192077;20236.190552378033;20293.492662683257;7091.078159839291;7346.853062938818;7426.480146389809;7501;7605.733799316998;4086.979565102215;4164.770937598461;4189.811458484375;4210.936835947463;4327.112941846914;3043.3761078978755;3263.951040734391;3309.0072978106473;3316.4527852904484;3322.817245051497;650.768977013163;652.517137318381;656.8883289840643;673.1988933169513;674.5547938360756;461.589843029548;487.3007234618334;511.2433598274574;547.3623228938322;623.8409205652621;7671.964284821589;7853.607319634109;8195.590220488952;8301.626426810808;8740.431871928304;563.5576072782927;571.9799807006818;576.4926864359421;595.4968051996057;608.996955015222;3677.558692956862;4087.4686290782006;4234.70357075008;4404.603585677304;4522.751248681354;65903.34096659068;81065.18934810694;100401.49799250957;106780.39829402565;109503;58114.709426452595;58541.70729146327;79359.60320198363;84366;84629.15370846336;54563.45436545663;76926.84609730859;79350.80973785398;80045.59977200077;85127.5743621278;8803.691870784492;9429.198518126037;9430;9477.24182065447;9486.383385080224;45143.96852141727;51501.96996060133;55228.723856380464;56275;56470.717646411504;73824.89262660957;74945.25054749382;76947;77636;79181.60409197917;11502.19484636101;11932.105092117952;12064.215825971283;12430.627081187578;12452.377381130726;12592.740725927333;13227;13613;14124.152550846982;14242.715145697235;11465.541378344773;12507.624771256877;12815.231086134861;13017.21896686202;13383.263920484078;12805.48778048905;12836.614901552968;12887.871121288666;12970.546030888874;13326.600201993955;12504;12643.936780316408;12693.238405695687;13102.737945240851;13128.081034327506;9948.290806922432;10608.89391106079;10615.416152111708;10625.628103016357;11002.229843910849;10730.517126729472;10744.83882741744;11008.339499630049;11019.614313498989;11072.723181920517 14:32:32 INFO - PROCESS | 1995 | __end_tp_report 14:32:32 INFO - PROCESS | 1995 | __start_cc_report 14:32:32 INFO - PROCESS | 1995 | _x_x_mozilla_cycle_collect,1921 14:32:32 INFO - PROCESS | 1995 | __end_cc_report 14:32:32 INFO - PROCESS | 1995 | __startTimestamp1479162752649__endTimestamp 14:32:32 INFO - PROCESS | 1995 | 14:32:32 INFO - PROCESS | 1995 | ------- Summary: start ------- 14:32:32 INFO - PROCESS | 1995 | Number of tests: 6 14:32:32 INFO - PROCESS | 1995 | 14:32:32 INFO - PROCESS | 1995 | [#0] dojo.html Cycles:165 Average:5604.19 Median:5694.26 stddev:649.88 (11.4%) stddev-sans-first:610.23 14:32:32 INFO - PROCESS | 1995 | Values: 2686.4 3223.8 3263.1 3277.3 3299.4 5511.1 5521.6 5571.3 5574.9 5626.0 5530.0 5555.1 5626.5 5638.7 5639.1 5018.6 5074.9 5091.5 5126.1 5141.9 5098.2 5192.2 5192.5 5219.3 5227.5 5194.2 5197.8 5222.2 5252.8 5259.4 5063.3 5079.5 5128.8 5139.2 5145.3 5142.2 5142.3 5161.9 5197.0 5214.7 5603.9 5610.5 5612.8 5620.0 5665.2 5866.2 5956.7 5964.8 6095.6 6109.3 4328.8 6117.3 6119.3 6124.6 6135.8 5641.7 5646.8 5654.3 5662.7 5662.9 6175.6 6197.2 6222.1 6250.7 6319.2 5985.8 5997.7 6009.3 6016.8 6035.3 6152.3 6155.5 6181.6 6188.6 6194.0 5912.4 5969.6 5986.1 5997.2 6062.9 6117.9 6127.9 6131.3 6131.7 6137.1 4835.0 4849.6 4859.0 4882.0 4891.0 4296.7 4298.7 4323.4 4338.2 4362.3 5638.5 5646.6 5656.5 5658.0 5658.4 6107.5 6117.4 6119.2 6131.3 6138.4 5555.9 5611.7 5630.2 5638.9 5653.4 5130.0 5267.3 5285.5 5289.3 5291.1 6209.8 6232.0 6238.5 6256.7 6257.0 5378.5 6087.6 6119.4 6182.9 6198.2 6318.7 6344.6 6349.4 6350.1 6352.9 5869.8 6067.5 6073.9 6074.1 6092.6 5983.1 6029.3 6039.3 6047.2 6107.5 4378.6 5886.6 5940.5 5944.5 5957.5 5823.8 5829.1 5829.6 5836.7 5843.8 5652.5 5684.9 5690.5 5699.2 5740.1 5876.6 5878.7 5905.0 5913.8 5950.8 5692.6 5694.3 5734.1 5749.8 5812.9 14:32:32 INFO - PROCESS | 1995 | 14:32:32 INFO - PROCESS | 1995 | [#1] ext.html Cycles:180 Average:65757.36 Median:38849.42 stddev:143644.20 (369.7%) stddev-sans-first:143963.51 14:32:32 INFO - PROCESS | 1995 | Values: 468.5 483.7 504.6 513.6 569.2 37120.7 39409.2 39414.4 39421.0 39464.8 39872.0 40197.6 40226.2 40286.6 40327.4 9064.0 9300.5 9302.9 9303.1 9303.3 9340.8 9442.2 9456.2 9460.9 9466.1 9425.5 9478.5 9479.5 9487.3 9493.1 9350.7 9417.7 9418.8 9437.6 9442.6 9197.0 9200.7 9211.4 9244.8 9315.7 38505.0 38814.6 38884.2 38885.8 38927.0 3206.8 3352.5 3394.8 3597.8 3924.9 24099.5 29228.9 30285.7 30547.1 31093.7 3197.9 3231.9 3235.1 3257.2 3315.8 356463.2 360668.0 362187.0 362313.4 363225.0 56639.7 56710.9 57229.9 57395.0 57402.4 50728.5 61183.0 61184.0 61421.0 61463.7 14765.8 14993.9 15019.5 15054.4 15092.2 11547.4 11567.3 11571.2 11591.3 11613.3 13416.7 16443.3 16560.3 16574.8 16693.9 6525.2 6548.0 6648.8 6658.8 6695.2 674.5 859.2 878.4 995.4 1024.1 28050.3 28704.0 29258.9 29377.0 30221.0 847.7 1025.1 1073.7 1076.5 1090.6 14179.3 14671.0 14710.0 15165.8 15174.7 631453.0 635212.0 636018.0 637485.0 637667.0 54195.2 54556.2 54561.2 54658.5 54725.5 1320.2 1345.3 1357.0 1361.0 1367.9 27562.3 28415.3 28800.4 28948.0 28953.1 45618.8 48334.0 48845.0 48876.0 48912.8 525686.3 585133.0 586060.0 586406.1 587417.0 37537.6 38297.6 38624.0 38769.6 38807.2 44023.9 44481.8 44812.6 45617.3 45682.0 37278.4 37465.6 37890.8 38622.4 38743.8 30825.2 37258.3 38439.6 38461.4 39642.8 36494.5 36991.8 37005.8 37036.3 37269.6 28996.3 29302.7 32001.7 33482.0 33759.8 24723.5 24739.5 25015.9 25363.4 25448.5 14:32:32 INFO - PROCESS | 1995 | 14:32:32 INFO - PROCESS | 1995 | [#2] jquery.html Cycles:180 Average:33491.78 Median:17710.36 stddev:98038.49 (553.6%) stddev-sans-first:98281.92 14:32:32 INFO - PROCESS | 1995 | Values: 346.9 370.6 371.6 382.3 399.0 14760.8 14963.1 15784.8 19645.6 20401.5 17870.9 18806.3 18845.0 18899.0 19080.6 5580.8 5940.9 5958.2 5961.6 5964.0 5279.7 5292.8 5829.8 5885.0 5889.8 5682.5 5701.2 5840.7 5847.9 5852.4 4252.5 5321.3 5506.7 5806.6 5914.7 5471.8 5601.8 5700.2 5729.1 5730.6 12944.6 13239.2 13253.1 13265.3 15139.8 2289.1 2290.0 2305.5 2335.1 2382.4 8805.9 9484.3 9751.6 9875.4 10179.9 1571.4 1807.5 1867.8 1871.6 1873.5 117568.0 119746.4 124869.0 126588.0 127993.0 9031.8 11282.4 11300.8 11396.8 11570.9 14361.4 14372.0 14390.5 14393.8 14414.7 7832.5 8145.4 8153.1 8261.3 8301.3 7708.2 7898.0 7919.9 8039.0 8082.9 3823.3 4458.9 4464.2 4532.6 4572.3 1822.8 1863.4 1901.7 1901.8 1910.6 846.8 940.7 946.8 963.5 1001.6 17303.7 20080.7 21327.8 21388.6 21696.0 706.5 899.7 930.7 943.9 955.3 8589.7 8718.3 10797.8 11155.6 11197.0 577507.0 588042.0 588609.0 591247.0 592156.0 98894.5 112362.0 122255.0 125177.0 134821.3 960.5 971.7 984.6 1007.9 1008.1 17959.0 18219.7 18389.7 18617.8 18779.0 35050.1 41358.0 51113.2 51188.5 54932.0 55927.4 63965.7 67980.6 69198.7 71943.0 7662.9 7912.8 8006.0 8019.7 8208.3 10273.6 12542.9 12562.2 12978.5 13174.7 10696.6 10906.5 11220.5 11231.0 11287.8 10815.8 10904.7 11148.2 11218.6 11319.0 11122.3 11199.6 11405.5 11591.5 11603.4 7889.3 8103.2 8131.5 8316.5 8394.2 8857.9 10393.7 11068.0 11190.0 11270.8 14:32:32 INFO - PROCESS | 1995 | 14:32:32 INFO - PROCESS | 1995 | [#3] mootools.html Cycles:180 Average:5321.55 Median:4962.65 stddev:2946.87 (59.4%) stddev-sans-first:2939.87 14:32:32 INFO - PROCESS | 1995 | Values: 1330.6 1358.3 1389.0 1440.2 1694.0 6806.8 7505.2 7885.6 8376.6 8385.7 8255.5 8343.4 8346.7 8357.7 8369.2 2312.1 2339.7 2339.8 2343.3 2349.0 2297.7 2351.4 2352.1 2353.0 2366.4 2263.2 2264.1 2283.4 2292.4 2292.5 2244.7 2256.3 2278.4 2281.5 2285.7 2300.6 2319.3 2325.5 2326.1 2332.7 8122.7 8298.8 8375.6 8391.7 8393.3 4985.8 5190.8 5197.5 5199.4 5210.7 2779.3 3173.4 3245.2 3318.5 3340.4 986.2 1017.9 1019.4 1020.7 1022.3 10451.4 10758.4 10789.2 10796.6 10967.0 4155.5 4292.1 4325.2 4449.7 4455.0 8089.0 8104.3 8105.2 8106.7 8109.4 4956.7 4962.6 4962.7 4962.8 4966.1 3753.5 3979.8 4285.9 4439.5 4750.1 3549.3 3580.8 3662.9 3664.1 3665.7 1117.3 1122.1 1124.2 1129.0 1136.6 764.5 795.2 888.1 939.7 941.0 4193.5 5363.7 5701.3 5823.7 5899.5 829.4 838.1 880.6 881.4 966.7 2901.8 3674.5 3735.6 3861.2 3898.2 9625.6 10105.1 10178.0 10252.2 10279.9 8448.5 10096.6 10236.8 10238.5 10256.3 7045.3 7479.2 7523.5 7761.8 7872.8 4548.6 5054.7 5474.3 5485.2 5589.9 9268.2 9269.4 9394.4 9410.0 9537.4 8784.6 8840.8 9322.3 9580.2 9714.6 7017.7 7232.8 7256.6 7305.9 7314.8 7834.6 7939.6 8014.8 8015.1 8032.3 6915.7 6979.2 7000.0 7051.9 7177.0 6847.0 6897.6 6910.7 6988.5 7007.2 6655.1 6671.1 6680.0 6754.4 6785.2 4772.3 4807.3 4810.9 4846.4 4955.7 4820.3 4821.0 4837.6 4914.1 4944.8 14:32:32 INFO - PROCESS | 1995 | 14:32:32 INFO - PROCESS | 1995 | [#4] prototype.html Cycles:180 Average:4242.97 Median:4859.05 stddev:1322.37 (27.2%) stddev-sans-first:1291.91 14:32:32 INFO - PROCESS | 1995 | Values: 263.5 303.6 312.7 317.4 319.1 4528.9 5048.9 5056.4 5059.6 5089.9 4469.8 4852.5 4926.4 4939.9 4979.6 3882.9 3889.0 3889.4 3896.8 3942.7 2901.4 3329.4 3617.4 3745.4 3910.9 3357.7 3593.6 3721.9 3812.4 3869.0 3801.2 3811.2 3832.7 3842.3 3845.6 2889.7 2909.0 3286.5 3330.4 3337.2 4207.8 4228.7 4434.8 4858.7 4868.9 3400.9 4201.7 4318.2 4375.6 4388.8 5223.2 5233.9 5244.7 5244.8 5246.3 3051.4 3644.6 3655.3 3656.0 3662.2 5162.6 5398.8 5458.6 5465.5 5468.5 2968.9 3660.8 3669.7 3675.4 3682.8 3720.9 3734.5 3736.4 3753.2 3753.7 3287.3 3644.0 3989.8 4157.7 4381.3 5036.5 5170.6 5283.0 5289.3 5337.1 1005.4 1119.8 1155.0 1177.0 1206.2 722.5 776.9 791.7 810.2 850.2 4203.0 4621.2 4660.9 4663.4 4761.6 4725.7 4860.5 4898.0 4980.9 5067.8 4360.2 4661.9 4705.8 5108.3 5162.4 2550.5 2664.3 2742.5 2766.4 2772.3 5050.4 5294.5 5361.2 5373.6 5472.0 4294.7 4889.0 5110.3 5547.9 5565.8 4885.6 4911.0 5590.6 5591.8 5594.9 5182.2 5410.5 5463.8 5508.2 5510.0 4973.7 5388.9 5392.6 5412.6 5505.6 5349.3 5623.9 5624.6 5643.7 5679.2 3387.0 3661.5 3680.8 3694.8 3700.4 4730.6 5423.2 5441.3 5448.5 5461.9 4842.8 4859.4 4879.0 4968.9 5288.6 4784.7 5032.8 5053.6 5291.2 5317.4 4977.8 5176.5 5189.4 5196.2 5206.7 4436.3 5156.5 5267.5 5292.5 5313.0 5197.3 5203.8 5212.0 5214.9 5243.3 14:32:32 INFO - PROCESS | 1995 | 14:32:32 INFO - PROCESS | 1995 | [#5] yui.html Cycles:180 Average:18990.47 Median:24817.38 stddev:26787.28 (107.9%) stddev-sans-first:26827.22 14:32:32 INFO - PROCESS | 1995 | Values: 701.8 740.8 766.3 783.7 800.2 13595.4 13863.4 14327.0 14367.4 14402.9 12638.2 12741.8 12764.3 12774.2 12776.0 640.6 665.4 668.0 799.9 815.6 818.3 819.8 831.7 834.6 835.0 860.6 882.9 883.1 884.2 888.6 682.8 710.4 878.3 879.2 880.1 627.6 655.6 662.4 664.6 664.9 10932.2 12920.9 12929.0 13066.2 13193.9 12989.0 13172.5 13177.0 13274.1 13326.8 12123.8 12141.7 12167.3 12515.8 12724.9 4338.7 4366.4 4433.0 4483.7 4490.8 81515.0 82560.6 84947.6 85989.6 88262.1 10455.9 10467.6 10469.1 10486.6 10888.3 20195.8 20198.7 20213.4 20236.2 20293.5 7091.1 7346.9 7426.5 7501.0 7605.7 4087.0 4164.8 4189.8 4210.9 4327.1 3043.4 3264.0 3309.0 3316.5 3322.8 650.8 652.5 656.9 673.2 674.6 461.6 487.3 511.2 547.4 623.8 7672.0 7853.6 8195.6 8301.6 8740.4 563.6 572.0 576.5 595.5 609.0 3677.6 4087.5 4234.7 4404.6 4522.8 65903.3 81065.2 100401.5 106780.4 109503.0 58114.7 58541.7 79359.6 84366.0 84629.2 54563.5 76926.8 79350.8 80045.6 85127.6 8803.7 9429.2 9430.0 9477.2 9486.4 45144.0 51502.0 55228.7 56275.0 56470.7 73824.9 74945.3 76947.0 77636.0 79181.6 11502.2 11932.1 12064.2 12430.6 12452.4 12592.7 13227.0 13613.0 14124.2 14242.7 11465.5 12507.6 12815.2 13017.2 13383.3 12805.5 12836.6 12887.9 12970.5 13326.6 12504.0 12643.9 12693.2 13102.7 13128.1 9948.3 10608.9 10615.4 10625.6 11002.2 10730.5 10744.8 11008.3 11019.6 11072.7 14:32:32 INFO - PROCESS | 1995 | -------- Summary: end -------- 14:32:32 INFO - PROCESS | 1995 | 14:32:33 INFO - TEST-INFO | 1995: exit 0 14:32:33 INFO - TEST-OK | dromaeo_css | took 1120233ms 14:32:33 INFO - TEST-START | kraken 14:32:33 INFO - operating with platform_type : mac_ 14:32:33 INFO - Initialising browser for kraken test... 14:32:33 INFO - TEST-INFO | started process 1999 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpCdZUPQ/profile http://localhost:49667/getInfo.html) 14:32:35 INFO - PROCESS | 1999 | __metrics Screen width/height:1600/1200 14:32:35 INFO - PROCESS | 1999 | colorDepth:24 14:32:35 INFO - PROCESS | 1999 | Browser inner width/height: 1024/689 14:32:35 INFO - PROCESS | 1999 | __metrics 14:32:35 INFO - PROCESS | 1999 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:32:35 INFO - PROCESS | 1999 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:32:36 INFO - TEST-INFO | 1999: exit 0 14:32:36 INFO - Browser initialized. 14:32:36 INFO - Running cycle 1/1 for kraken test... 14:32:36 INFO - TEST-INFO | started process 2000 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpCdZUPQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:32:48 INFO - PROCESS | 2000 | RSS: Main: 241098752 14:32:48 INFO - PROCESS | 2000 | 14:32:51 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49667/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49667/tests/kraken/driver.html?testName=audio-beat-detection) 14:32:52 INFO - PROCESS | 2000 | RSS: Main: 291516416 14:32:52 INFO - PROCESS | 2000 | 14:32:55 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49667/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49667/tests/kraken/driver.html?testName=audio-dft) 14:32:55 INFO - PROCESS | 2000 | RSS: Main: 291442688 14:32:55 INFO - PROCESS | 2000 | 14:32:59 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49667/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49667/tests/kraken/driver.html?testName=audio-fft) 14:32:59 INFO - PROCESS | 2000 | RSS: Main: 386166784 14:32:59 INFO - PROCESS | 2000 | 14:33:02 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49667/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49667/tests/kraken/driver.html?testName=audio-oscillator) 14:33:02 INFO - PROCESS | 2000 | RSS: Main: 285220864 14:33:02 INFO - PROCESS | 2000 | 14:33:04 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49667/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49667/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:33:05 INFO - PROCESS | 2000 | RSS: Main: 290234368 14:33:05 INFO - PROCESS | 2000 | 14:33:08 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49667/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49667/tests/kraken/driver.html?testName=imaging-darkroom) 14:33:08 INFO - PROCESS | 2000 | RSS: Main: 325402624 14:33:08 INFO - PROCESS | 2000 | 14:33:11 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49667/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49667/tests/kraken/driver.html?testName=imaging-desaturate) 14:33:12 INFO - PROCESS | 2000 | RSS: Main: 328081408 14:33:12 INFO - PROCESS | 2000 | 14:33:15 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49667/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49667/tests/kraken/driver.html?testName=json-parse-financial) 14:33:15 INFO - PROCESS | 2000 | RSS: Main: 328114176 14:33:15 INFO - PROCESS | 2000 | 14:33:17 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49667/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49667/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:33:18 INFO - PROCESS | 2000 | RSS: Main: 303898624 14:33:18 INFO - PROCESS | 2000 | 14:33:19 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49667/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49667/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:33:20 INFO - PROCESS | 2000 | RSS: Main: 308953088 14:33:20 INFO - PROCESS | 2000 | 14:33:22 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49667/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49667/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:33:23 INFO - PROCESS | 2000 | RSS: Main: 309665792 14:33:23 INFO - PROCESS | 2000 | 14:33:26 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49667/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49667/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:33:27 INFO - PROCESS | 2000 | RSS: Main: 323727360 14:33:27 INFO - PROCESS | 2000 | 14:33:30 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49667/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49667/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:33:30 INFO - PROCESS | 2000 | RSS: Main: 318083072 14:33:30 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49667/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49667/tests/kraken/driver.html?testName=ai-astar) 14:33:32 INFO - PROCESS | 2000 | RSS: Main: 361328640 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | __start_tp_report 14:33:32 INFO - PROCESS | 2000 | _x_x_mozilla_page_load 14:33:32 INFO - PROCESS | 2000 | _x_x_mozilla_page_load_details 14:33:32 INFO - PROCESS | 2000 | |i|pagename|runs| 14:33:32 INFO - PROCESS | 2000 | |0;ai-astar;115;114;113;113;114;114;114;114;113;115 14:33:32 INFO - PROCESS | 2000 | |1;audio-beat-detection;146;113;113;148;113;111;113;107;142;112 14:33:32 INFO - PROCESS | 2000 | |2;audio-dft;195;264;202;184;182;181;182;182;178;180 14:33:32 INFO - PROCESS | 2000 | |3;audio-fft;81;69;59;91;81;85;82;59;83;59 14:33:32 INFO - PROCESS | 2000 | |4;audio-oscillator;85;85;123;101;97;68;88;68;91;127 14:33:32 INFO - PROCESS | 2000 | |5;imaging-gaussian-blur;117;130;87;86;86;84;89;86;88;83 14:33:32 INFO - PROCESS | 2000 | |6;imaging-darkroom;95;96;95;96;96;95;96;96;95;96 14:33:32 INFO - PROCESS | 2000 | |7;imaging-desaturate;81;81;80;79;82;81;82;96;122;115 14:33:32 INFO - PROCESS | 2000 | |8;json-parse-financial;53;53;54;54;56;56;56;54;57;55 14:33:32 INFO - PROCESS | 2000 | |9;json-stringify-tinderbox;65;67;73;75;70;70;69;69;72;71 14:33:32 INFO - PROCESS | 2000 | |10;stanford-crypto-aes;88;90;91;90;89;86;87;87;87;88 14:33:32 INFO - PROCESS | 2000 | |11;stanford-crypto-ccm;146;144;149;154;153;149;149;166;195;146 14:33:32 INFO - PROCESS | 2000 | |12;stanford-crypto-pbkdf2;173;173;172;172;173;173;170;174;171;173 14:33:32 INFO - PROCESS | 2000 | |13;stanford-crypto-sha256-iterative;66;67;66;67;68;65;67;66;65;66 14:33:32 INFO - PROCESS | 2000 | __end_tp_report 14:33:32 INFO - PROCESS | 2000 | __start_cc_report 14:33:32 INFO - PROCESS | 2000 | _x_x_mozilla_cycle_collect,2005 14:33:32 INFO - PROCESS | 2000 | __end_cc_report 14:33:32 INFO - PROCESS | 2000 | __startTimestamp1479162812628__endTimestamp 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | ------- Summary: start ------- 14:33:32 INFO - PROCESS | 2000 | Number of tests: 14 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.67 14:33:32 INFO - PROCESS | 2000 | Values: 115.0 114.0 113.0 113.0 114.0 114.0 114.0 114.0 113.0 115.0 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:113.00 stddev:16.40 (14.5%) stddev-sans-first:14.88 14:33:32 INFO - PROCESS | 2000 | Values: 146.0 113.0 113.0 148.0 113.0 111.0 113.0 107.0 142.0 112.0 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | [#2] audio-dft Cycles:10 Average:193.00 Median:183.00 stddev:26.05 (14.2%) stddev-sans-first:27.62 14:33:32 INFO - PROCESS | 2000 | Values: 195.0 264.0 202.0 184.0 182.0 181.0 182.0 182.0 178.0 180.0 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | [#3] audio-fft Cycles:10 Average:74.90 Median:81.50 stddev:12.22 (15.0%) stddev-sans-first:12.76 14:33:32 INFO - PROCESS | 2000 | Values: 81.0 69.0 59.0 91.0 81.0 85.0 82.0 59.0 83.0 59.0 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | [#4] audio-oscillator Cycles:10 Average:93.30 Median:85.00 stddev:19.84 (23.3%) stddev-sans-first:20.81 14:33:32 INFO - PROCESS | 2000 | Values: 85.0 85.0 123.0 101.0 97.0 68.0 88.0 68.0 91.0 127.0 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | [#5] imaging-gaussian-blur Cycles:10 Average:93.60 Median:86.00 stddev:16.15 (18.8%) stddev-sans-first:14.74 14:33:32 INFO - PROCESS | 2000 | Values: 117.0 130.0 87.0 86.0 86.0 84.0 89.0 86.0 88.0 83.0 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | [#6] imaging-darkroom Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 14:33:32 INFO - PROCESS | 2000 | Values: 95.0 96.0 95.0 96.0 96.0 95.0 96.0 96.0 95.0 96.0 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | [#7] imaging-desaturate Cycles:10 Average:89.90 Median:81.00 stddev:15.91 (19.6%) stddev-sans-first:16.54 14:33:32 INFO - PROCESS | 2000 | Values: 81.0 81.0 80.0 79.0 82.0 81.0 82.0 96.0 122.0 115.0 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.50 stddev:1.40 (2.5%) stddev-sans-first:1.32 14:33:32 INFO - PROCESS | 2000 | Values: 53.0 53.0 54.0 54.0 56.0 56.0 56.0 54.0 57.0 55.0 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.50 stddev:2.88 (4.1%) stddev-sans-first:2.40 14:33:32 INFO - PROCESS | 2000 | Values: 65.0 67.0 73.0 75.0 70.0 70.0 69.0 69.0 72.0 71.0 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:88.50 stddev:1.64 (1.8%) stddev-sans-first:1.73 14:33:32 INFO - PROCESS | 2000 | Values: 88.0 90.0 91.0 90.0 89.0 86.0 87.0 87.0 87.0 88.0 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | [#11] stanford-crypto-ccm Cycles:10 Average:155.10 Median:151.00 stddev:15.34 (10.2%) stddev-sans-first:15.91 14:33:32 INFO - PROCESS | 2000 | Values: 146.0 144.0 149.0 154.0 153.0 149.0 149.0 166.0 195.0 146.0 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.40 Median:173.00 stddev:1.17 (0.7%) stddev-sans-first:1.22 14:33:32 INFO - PROCESS | 2000 | Values: 173.0 173.0 172.0 172.0 173.0 173.0 170.0 174.0 171.0 173.0 14:33:32 INFO - PROCESS | 2000 | 14:33:32 INFO - PROCESS | 2000 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:0.95 (1.4%) stddev-sans-first:1.00 14:33:32 INFO - PROCESS | 2000 | Values: 66.0 67.0 66.0 67.0 68.0 65.0 67.0 66.0 65.0 66.0 14:33:32 INFO - PROCESS | 2000 | -------- Summary: end -------- 14:33:32 INFO - PROCESS | 2000 | 14:33:33 INFO - TEST-INFO | 2000: exit 0 14:33:33 INFO - TEST-OK | kraken | took 60258ms 14:33:33 INFO - SUITE-END | took 1180s 14:33:33 INFO - Completed test suite (00:19:40) 14:33:33 INFO - javascript benchmark 14:33:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2686.3821321096148, 3223.7582181336406, 3263.0747858763325, 3277.3445310937786, 3299.356625458035, 5511.063119269724, 5521.585881058916, 5571.303587051619, 5574.8606284842845, 5626.0, 5530.0, 5555.138953462219, 5626.465485778855, 5638.689872057045, 5639.097744360903, 5018.573421259197, 5074.898502029957, 5091.516305950947, 5126.128558145124, 5141.922871156936, 5098.158803797369, 5192.169252919533, 5192.480751924815, 5219.295395121648, 5227.4511176326505, 5194.220866869962, 5197.7920883164625, 5222.242774797652, 5252.842414727565, 5259.421463639016, 5063.291139240472, 5079.492050794928, 5128.8461346159675, 5139.229115632685, 5145.33110695611, 5142.177251639757, 5142.30578871855, 5161.922571161435, 5196.974015129938, 5214.713190774506, 5603.943960560383, 5610.4670056344585, 5612.831615051555, 5619.971900140514, 5665.1502274658715, 5866.208061911672, 5956.7319470623925, 5964.761409543613, 6095.603785753912, 6109.327973922865, 4328.761918094504, 6117.309816958451, 6119.289302195927, 6124.564025909317, 6135.815925522197, 5641.661500309994, 5646.802361917355, 5654.349749778746, 5662.745176467069, 5662.886742265132, 6175.598586091979, 6197.163382943335, 6222.12890195364, 6250.718717657716, 6319.178506794087, 5985.7904973326595, 5997.730102145414, 6009.338972712998, 6016.849578760479, 6035.336113027652, 6152.292486364126, 6155.4767844732805, 6181.567290289637, 6188.597741146811, 6194.0, 5912.40875912414, 5969.582129250911, 5986.07215881539, 5997.190379298826, 6062.939370606237, 6117.9187529241, 6127.877442451215, 6131.327140476762, 6131.743695907034, 6137.113808755727, 4834.975825120922, 4849.563539281482, 4858.95141048585, 4882.048000639841, 4890.95109048912, 4296.699231053859, 4298.720583162084, 4323.373110898965, 4338.154059958341, 4362.323839804835, 5638.464345887134, 5646.632968857011, 5656.547476201894, 5658.009848276617, 5658.405867383948, 6107.450329470369, 6117.388261173847, 6119.204503414617, 6131.264248290234, 6138.447539721446, 5555.944440555542, 5611.663300202001, 5630.183623374588, 5638.85902852432, 5653.406392328829, 5129.9743501283, 5267.2889159962915, 5285.497877701612, 5289.338832645919, 5291.074062039204, 6209.813705588839, 6231.968840155861, 6238.532110091852, 6256.687165641791, 6256.968715156395, 5378.513092925929, 6087.619726819819, 6119.380693981147, 6182.876342473215, 6198.163247961648, 6318.715657795502, 6344.619322840645, 6349.428551430394, 6350.1109844622715, 6352.936470635234, 5869.7945571904775, 6067.484263837623, 6073.939260607514, 6074.058520929263, 6092.603980741238, 5983.072623743326, 6029.276486821609, 6039.335673076046, 6047.244094488189, 6107.5226075721885, 4378.605925466723, 5886.646801191942, 5940.465358117617, 5944.494717949023, 5957.523398128226, 5823.7670493181495, 5829.067349224104, 5829.562782791392, 5836.737346819318, 5843.824685259449, 5652.491275785199, 5684.857878553069, 5690.459406356389, 5699.202111704281, 5740.13897915316, 5876.647401155944, 5878.735456904364, 5905.0, 5913.763449461973, 5950.7917222896995, 5692.573057020657, 5694.259746232963, 5734.139879018181, 5749.770009199585, 5812.912806307993], "value": 5562.092825997247, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.53380886018414, 483.7411984588244, 504.57867680486794, 513.6378852908698, 569.1548051144053, 37120.70077547286, 39409.211815763665, 39414.40878386827, 39421.01447463815, 39464.80267598662, 39872.003199920015, 40197.59802401975, 40226.195476090536, 40286.59713402865, 40327.395089073616, 9063.95468022659, 9300.534973251344, 9302.86045709315, 9303.116203960646, 9303.348765586412, 9340.766480838, 9442.244620430383, 9456.243500519942, 9460.905390946105, 9466.053394660548, 9425.528723563828, 9478.478683672396, 9479.47862867542, 9487.28845336599, 9493.05069493052, 9350.672726454575, 9417.717468475958, 9418.764530886714, 9437.575309111107, 9442.622295108222, 9197.0, 9200.723978280595, 9211.355205135644, 9244.815103697889, 9315.72052838416, 38505.0, 38814.61185388138, 38884.22231555353, 38885.80557097225, 38927.0, 3206.8236247006407, 3352.547406100194, 3394.8132852693093, 3597.80212088335, 3924.921501569981, 24099.518009639884, 29228.853855730584, 30285.69714302851, 30547.098025687523, 31093.689063109305, 3197.9200519986953, 3231.87072517099, 3235.142687187897, 3257.1531401835455, 3315.834208289576, 356463.2176839125, 360668.0, 362187.0, 362313.37686623324, 363225.0, 56639.71680141573, 56710.865782684115, 57229.85540289254, 57395.0, 57402.425975740545, 50728.492715072374, 61183.0, 61184.0, 61421.0, 61463.692681536304, 14765.77851332231, 14993.925030374778, 15019.474318398803, 15054.397824086915, 15092.245387730569, 11547.364894930692, 11567.30596164216, 11571.190016698918, 11591.304521728724, 11613.30320180792, 13416.664583385298, 16443.260053297632, 16560.337586496404, 16574.75137872933, 16693.913260842393, 6525.249596296487, 6548.0384367457955, 6648.800535983928, 6658.766943156967, 6695.230048544382, 674.4711195626384, 859.2352806002663, 878.4465786554557, 995.3629677006721, 1024.078329503438, 28050.2987425316, 28703.995360162295, 29258.853705731333, 29376.971805987116, 30221.0, 847.6990668312784, 1025.092792878308, 1073.6564299424265, 1076.5101878645198, 1090.596479302663, 14179.291035448186, 14671.046381985137, 14710.0, 15165.772513412308, 15174.696506069817, 631452.9555578964, 635212.0, 636018.0, 637485.0, 637667.0, 54195.18707219395, 54556.18165727517, 54561.181582276295, 54658.45341546613, 54725.45274547204, 1320.18148747777, 1345.2937207965897, 1356.9796453053211, 1361.0, 1367.897407694407, 27562.310942226606, 28415.289617758895, 28800.42399152047, 28948.0, 28953.13140605785, 45618.77190614026, 48334.03331933412, 48845.02309953852, 48876.02247955092, 48912.75543622259, 525686.3166630879, 585133.0, 586060.0, 586406.0679696574, 587417.0, 37537.624623754506, 38297.61702382941, 38624.0, 38769.61230387773, 38807.22385552217, 44023.89940251519, 44481.77759111184, 44812.551874480836, 45617.31574026326, 45682.0, 37278.44082338821, 37465.625343747306, 37890.8105459471, 38622.42066369063, 38743.8376848695, 30825.22936926505, 37258.25483490261, 38439.61560384472, 38461.423078653286, 39642.80178599089, 36494.4525832118, 36991.81504092462, 37005.81497092497, 37036.25927481381, 37269.627303726615, 28996.27509312284, 29302.70697293085, 32001.67998319987, 33481.99554013384, 33759.83120084384, 24723.505529889662, 24739.505209895342, 25015.87492062528, 25363.36591585225, 25448.491030178924], "value": 19228.748424297733, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.9375512407769, 370.60160327647776, 371.5597017534222, 382.2851268128599, 398.97406668566543, 14760.77858832117, 14963.102213867161, 15784.842151578467, 19645.607087858236, 20401.48996275094, 17870.910645446755, 18806.34177803774, 18845.0, 18899.0, 19080.61838763224, 5580.804671836487, 5940.88118237635, 5958.195643588104, 5961.612495187821, 5963.970180149093, 5279.70961597112, 5292.782659988205, 5829.795957141501, 5884.999550076497, 5889.7644094236175, 5682.488576028157, 5701.230333904932, 5840.707964601753, 5847.883042339172, 5852.3562408135285, 4252.510961239466, 5321.3348331458565, 5506.669599823983, 5806.59353845231, 5914.704264786744, 5471.753771080311, 5601.795613942981, 5700.201971723962, 5729.083346664514, 5730.598858079936, 12944.63439505726, 13239.20564766117, 13253.07228493996, 13265.336733163304, 15139.772903406458, 2289.1186893046297, 2290.038183962723, 2305.5388922215625, 2335.1359996801293, 2382.4282172278704, 8805.855238818913, 9484.288678349083, 9751.561179746928, 9875.40747555149, 10179.949100254451, 1571.4185751272103, 1807.502936692407, 1867.785204701463, 1871.6350311689225, 1873.5409824592925, 117568.0, 119746.40126799398, 124869.0, 126588.0, 127993.0, 9031.81936361269, 11282.379469129113, 11300.830487542693, 11396.829047564293, 11570.94214528922, 14361.353739081766, 14372.0, 14390.496332628307, 14393.78409323861, 14414.711705765825, 7832.451728379073, 8145.389095817837, 8153.1439198884445, 8261.339092872677, 8301.29438997692, 7708.190639982833, 7897.960510197528, 7919.930809340781, 8038.959805201053, 8082.878756818653, 3823.311803875329, 4458.878784850617, 4464.17412778637, 4532.592066713945, 4572.314152877095, 1822.8450581700438, 1863.3602131827417, 1901.7242499837446, 1901.8193271639173, 1910.646530391882, 846.8391005709019, 940.6707652321633, 946.8248374050822, 963.5326866469757, 1001.5643195210107, 17303.653926921645, 20080.69878951817, 21327.78672213258, 21388.572228555342, 21696.0, 706.5230969095943, 899.6536333511646, 930.6510058727977, 943.9339246252696, 955.2787645327733, 8589.742307730778, 8718.346124040723, 10797.83803242952, 11155.609553665745, 11197.0, 577507.0, 588042.0, 588609.0, 591247.0, 592156.0, 98894.52763680965, 112362.0, 122255.0, 125177.0, 134821.32589337186, 960.4525420510242, 971.7133445633697, 984.625842179967, 1007.9193664506822, 1008.0876806490138, 17959.01225432614, 18219.726704099183, 18389.72415413743, 18617.813821861608, 18779.0, 35050.12374690653, 41358.0, 51113.2110376135, 51188.488115119864, 54932.0, 55927.44072559222, 63965.68017159885, 67980.64038719297, 69198.65400672964, 71943.0, 7662.885056724042, 7912.802179945547, 8005.9599702001115, 8019.679212831421, 8208.261256486945, 10273.640422585473, 12542.874571254171, 12562.183458075197, 12978.480860765463, 13174.670633233862, 10696.62561810333, 10906.50920708554, 11220.495077721358, 11231.045361144394, 11287.7742445149, 10815.837762433412, 10904.672859814218, 11148.163887708291, 11218.55125794959, 11319.0, 11122.332660040423, 11199.608013719806, 11405.486753095965, 11591.478383472595, 11603.419829008348, 7889.289963903277, 8103.230193131643, 8131.471454355448, 8316.542590157358, 8394.202550757669, 8857.911420885708, 10393.740156496147, 11068.0, 11190.0, 11270.774584508428], "value": 9078.123551746126, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1330.5941687785235, 1358.286899377826, 1388.9791003612343, 1440.1934916446792, 1693.9745903811445, 6806.795796126118, 7505.174430812613, 7885.605719714013, 8376.58117094146, 8385.706500272492, 8255.463394879329, 8343.415960882741, 8346.707865224718, 8357.707480238192, 8369.20492553208, 2312.09828167015, 2339.7192336919593, 2339.8245131615113, 2343.2735851885886, 2348.988255058722, 2297.7012988311494, 2351.423901144222, 2352.070931981866, 2352.988235058831, 2366.420227044368, 2263.162629826966, 2264.094362255095, 2283.4291427143216, 2292.4039749665126, 2292.5300313435705, 2244.708187935574, 2256.3343813575034, 2278.384836094245, 2281.4752606900506, 2285.680004799321, 2300.6088964875844, 2319.3273950554317, 2325.546518428907, 2326.1160758911674, 2332.696749422564, 8122.715704950358, 8298.83402331956, 8375.623096960651, 8391.664333426656, 8393.328533717287, 4985.750712464399, 5190.792368305263, 5197.532222100029, 5199.350081239845, 5210.6613070150315, 2779.2634951737805, 3173.3970545596494, 3245.2049247934406, 3318.502224666295, 3340.448825943716, 986.2159583131356, 1017.8931212222684, 1019.418931209211, 1020.7193021918957, 1022.2639699416409, 10451.372917624967, 10758.408287544182, 10789.244752867302, 10796.5681372745, 10967.012968832763, 4155.501339839178, 4292.098659281523, 4325.243082460557, 4449.688521803443, 4455.0, 8089.02931648206, 8104.270615644502, 8105.230003149692, 8106.7162649308175, 8109.351251899834, 4956.677815941952, 4962.60299176065, 4962.702237865739, 4962.751862406865, 4966.056449274699, 3753.474513568048, 3979.820908059144, 4285.892852678646, 4439.511653718088, 4750.14497390473, 3549.3256281306467, 3580.820958952094, 3662.853485860589, 3664.1022949377575, 3665.651763082503, 1117.3240189685287, 1122.141561705296, 1124.201816710145, 1129.0, 1136.6362763915467, 764.5107131435885, 795.2127393880132, 888.1296329596902, 939.7039932421266, 940.9576569054409, 4193.475815523059, 5363.704996225167, 5701.344345400249, 5823.708814559255, 5899.46904778572, 829.3613917283778, 838.0739283092217, 880.6125304865839, 881.3962435731545, 966.6616684160488, 2901.8113822601886, 3674.467202255658, 3735.5517337919623, 3861.227754449121, 3898.1813819098306, 9625.614975401046, 10105.14106300958, 10178.0, 10252.23108266883, 10279.897201028043, 8448.493090414595, 10096.596136154618, 10236.846447303296, 10238.488075596339, 10256.282060255711, 7045.330693584101, 7479.214682458482, 7523.510971786816, 7761.805954851174, 7872.803179920548, 4548.56788605082, 5054.721990290569, 5474.288342515448, 5485.232067510631, 5589.888202235851, 9268.212201962908, 9269.397489163182, 9394.436333819993, 9409.952950235205, 9537.380070295409, 8784.648614055366, 8840.838360447662, 9322.347435679438, 9580.23358131334, 9714.611415543299, 7017.719291228498, 7232.819179520554, 7256.600886951268, 7305.890411643723, 7314.780556583311, 7834.608269586384, 7939.6030198491, 8014.83970320602, 8015.11833698281, 8032.277095061472, 6915.654217289015, 6979.232284448605, 6999.965000174967, 7051.858962820817, 7177.031100801325, 6847.041414202115, 6897.62063086515, 6910.654467276516, 6988.510804243654, 7007.229204787367, 6655.068290439245, 6671.066050752996, 6679.966600166968, 6754.358335958273, 6785.1518560179975, 4772.284157376422, 4807.326974223542, 4810.903781924412, 4846.418429788448, 4955.702657840541, 4820.34925285082, 4821.0, 4837.56461918415, 4914.0908931847725, 4944.752762361795], "value": 4296.022919598808, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [263.5388070875967, 303.64321921741964, 312.7310512958855, 317.4222914295981, 319.10013761193443, 4528.890421846652, 5048.939722658241, 5056.443791182976, 5059.620528460367, 5089.949100508994, 4469.776511174445, 4852.50096200457, 4926.3842019747535, 4939.901201975958, 4979.626528010395, 3882.941755873664, 3888.969423102873, 3889.4165875118674, 3896.8051597420017, 3942.664873485757, 2901.3762041161144, 3329.400707872582, 3617.40312848381, 3745.4381842723537, 3910.9022274443223, 3357.6810203030673, 3593.568771747394, 3721.8511259549587, 3812.4281357796276, 3868.9806550967205, 3801.1944326444996, 3811.246826459832, 3832.693384529231, 3842.2507611016003, 3845.5962123977, 2889.7110288971144, 2908.956365654517, 3286.4741641337273, 3330.400527904976, 3337.182390314364, 4207.768572728499, 4228.6617070634275, 4434.756088415136, 4858.732769697665, 4868.9269660955115, 3400.914977125567, 4201.70588058836, 4318.2227199104145, 4375.628071613916, 4388.824447022115, 5223.242629818693, 5233.921491177597, 5244.737763111829, 5244.763985620618, 5246.265522826808, 3051.3897220555978, 3644.6173151819207, 3655.3054919565197, 3655.9946014845864, 3662.212624285765, 5162.638615296931, 5398.838034858961, 5458.61789674723, 5465.453454654582, 5468.453154684539, 2968.881244750186, 3660.798656073888, 3669.7247706422127, 3675.448682697563, 3682.797446140434, 3720.906977325534, 3734.533183352081, 3736.3834967230105, 3753.2493501299305, 3753.7372383933407, 3287.3260981498734, 3643.9796856880116, 3989.7606143630896, 4157.734034950959, 4381.342798580238, 5036.496350364934, 5170.586353091744, 5283.0, 5289.338832645919, 5337.077248222553, 1005.3917379985149, 1119.8376235445814, 1155.0297749889965, 1176.988230117705, 1206.1918514595243, 722.5086940880146, 776.9106552746506, 791.666042718022, 810.2140923304386, 850.2050582705191, 4203.0, 4621.237495813153, 4660.883477913011, 4663.370444989883, 4761.571458568746, 4725.692829966041, 4860.538248866352, 4898.020395920759, 4980.9252861207115, 5067.771950262247, 4360.171567402183, 4661.930071048937, 4705.788239529229, 5108.310378098983, 5162.367192731734, 2550.525989789901, 2664.254008877517, 2742.4515096980285, 2766.405222877091, 2772.265349682336, 5050.419201791768, 5294.523492885582, 5361.222622719683, 5373.570114390839, 5471.972640136773, 4294.656427485794, 4888.951110488921, 5110.313596513141, 5547.920903898375, 5565.833025009256, 4885.5847252983895, 4911.0, 5590.580706447033, 5591.832245032656, 5594.8810237952575, 5182.24857395683, 5410.48600382963, 5463.836084917459, 5508.228847961209, 5509.9806535791, 4973.701577905337, 5388.919166212589, 5392.568594512508, 5412.621116521806, 5505.642133261325, 5349.331333583302, 5623.943760562342, 5624.578156638187, 5643.661380317194, 5679.204911312502, 3387.0346951118813, 3661.5423072115987, 3680.7975561344383, 3694.796786176786, 3700.38943574318, 4730.574248317668, 5423.240746295601, 5441.319835020622, 5448.482394172705, 5461.863453413537, 4842.806287748592, 4859.3682821233015, 4879.0, 4968.931679688884, 5288.629795914249, 4784.736839473862, 5032.823851165338, 5053.610257179305, 5291.206319052173, 5317.4270127690925, 4977.751112444435, 5176.534111929945, 5189.429162792015, 5196.168613021899, 5206.661566998133, 4436.336373859803, 5156.535911767959, 5267.525922666979, 5292.470752924677, 5313.0247061645605, 5197.272381866617, 5203.765830537711, 5211.983663185794, 5214.947850521598, 5243.292155558951], "value": 3818.3112304963142, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.7683964642052, 740.8073900785794, 766.3218052023959, 783.6669415498412, 800.2157885272435, 13595.388207530661, 13863.376148073336, 14327.0, 14367.425302987895, 14402.927985360071, 12638.241705497676, 12741.808872866892, 12764.297963611998, 12774.233545987247, 12776.041796865225, 640.6252342379713, 665.4111111666186, 667.9599224046549, 799.8800179973022, 815.5881279953616, 818.3126174013827, 819.8401311744212, 831.6922738586701, 834.6160766047625, 835.0021724039775, 860.6213266162899, 882.9381943263974, 883.0904168706243, 884.2086332732201, 888.5557221389305, 682.7780971184385, 710.3819676881095, 878.3061381508601, 879.226280872831, 880.1110878013221, 627.645380360095, 655.5673255651247, 662.3773652766432, 664.5879554676084, 664.8836453620645, 10932.234743567953, 12920.935395323057, 12929.0, 13066.150700204456, 13193.868061319456, 12989.0, 13172.53896113641, 13177.0, 13274.137181083295, 13326.800097998537, 12123.818142727867, 12141.69645758863, 12167.330796806173, 12515.812262816065, 12724.936375318157, 4338.652907767371, 4366.400547784798, 4433.046894917576, 4483.730976141442, 4490.797914093873, 81515.0, 82560.58719706423, 84947.57526212391, 85989.57005214997, 88262.11737882603, 10455.947720261502, 10467.592453735531, 10469.057784799405, 10486.63296784624, 10888.346699198073, 20195.798042019393, 20198.697019544426, 20213.393598192077, 20236.190552378033, 20293.492662683257, 7091.078159839291, 7346.853062938818, 7426.480146389809, 7501.0, 7605.733799316998, 4086.979565102215, 4164.770937598461, 4189.811458484375, 4210.936835947463, 4327.112941846914, 3043.3761078978755, 3263.951040734391, 3309.0072978106473, 3316.4527852904484, 3322.817245051497, 650.768977013163, 652.517137318381, 656.8883289840643, 673.1988933169513, 674.5547938360756, 461.589843029548, 487.3007234618334, 511.2433598274574, 547.3623228938322, 623.8409205652621, 7671.964284821589, 7853.607319634109, 8195.590220488952, 8301.626426810808, 8740.431871928304, 563.5576072782927, 571.9799807006818, 576.4926864359421, 595.4968051996057, 608.996955015222, 3677.558692956862, 4087.4686290782006, 4234.70357075008, 4404.603585677304, 4522.751248681354, 65903.34096659068, 81065.18934810694, 100401.49799250957, 106780.39829402565, 109503.0, 58114.709426452595, 58541.70729146327, 79359.60320198363, 84366.0, 84629.15370846336, 54563.45436545663, 76926.84609730859, 79350.80973785398, 80045.59977200077, 85127.5743621278, 8803.691870784492, 9429.198518126037, 9430.0, 9477.24182065447, 9486.383385080224, 45143.96852141727, 51501.96996060133, 55228.723856380464, 56275.0, 56470.717646411504, 73824.89262660957, 74945.25054749382, 76947.0, 77636.0, 79181.60409197917, 11502.19484636101, 11932.105092117952, 12064.215825971283, 12430.627081187578, 12452.377381130726, 12592.740725927333, 13227.0, 13613.0, 14124.152550846982, 14242.715145697235, 11465.541378344773, 12507.624771256877, 12815.231086134861, 13017.21896686202, 13383.263920484078, 12805.48778048905, 12836.614901552968, 12887.871121288666, 12970.546030888874, 13326.600201993955, 12504.0, 12643.936780316408, 12693.238405695687, 13102.737945240851, 13128.081034327506, 9948.290806922432, 10608.89391106079, 10615.416152111708, 10625.628103016357, 11002.229843910849, 10730.517126729472, 10744.83882741744, 11008.339499630049, 11019.614313498989, 11072.723181920517], "value": 7043.908501105708, "unit": "score"}], "name": "dromaeo_css", "value": 6944.875352312552, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 113.0, 113.0, 114.0, 114.0, 114.0, 114.0, 113.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 113.0, 113.0, 148.0, 113.0, 111.0, 113.0, 107.0, 142.0, 112.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 264.0, 202.0, 184.0, 182.0, 181.0, 182.0, 182.0, 178.0, 180.0], "value": 193.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 69.0, 59.0, 91.0, 81.0, 85.0, 82.0, 59.0, 83.0, 59.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 123.0, 101.0, 97.0, 68.0, 88.0, 68.0, 91.0, 127.0], "value": 93.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 130.0, 87.0, 86.0, 86.0, 84.0, 89.0, 86.0, 88.0, 83.0], "value": 93.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0], "value": 95.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 80.0, 79.0, 82.0, 81.0, 82.0, 96.0, 122.0, 115.0], "value": 89.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 54.0, 56.0, 56.0, 56.0, 54.0, 57.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 73.0, 75.0, 70.0, 70.0, 69.0, 69.0, 72.0, 71.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 91.0, 90.0, 89.0, 86.0, 87.0, 87.0, 87.0, 88.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 144.0, 149.0, 154.0, 153.0, 149.0, 149.0, 166.0, 195.0, 146.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 172.0, 172.0, 173.0, 173.0, 170.0, 174.0, 171.0, 173.0], "value": 172.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 68.0, 65.0, 67.0, 66.0, 65.0, 66.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1483.0, "alertThreshold": 2.0}]} 14:33:33 INFO - Return code: 0 14:33:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:33:33 INFO - # TBPL SUCCESS # 14:33:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:33:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:33:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:33:33 INFO - 2016-11-14 14:33:33.934 screenresolution[2004:15450] starting screenresolution argv=screenresolution get 14:33:33 INFO - 2016-11-14 14:33:33.936 screenresolution[2004:15450] Display 0: 1600x1200x32@60 14:33:33 INFO - 2016-11-14 14:33:33.943 screenresolution[2005:15453] starting screenresolution argv=screenresolution list 14:33:33 INFO - Available Modes on Display 0 14:33:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:33:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:33:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:33:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:33:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:33:34 INFO - Intel Iris: 14:33:34 INFO - Chipset Model: Intel Iris 14:33:34 INFO - Type: GPU 14:33:34 INFO - Bus: Built-In 14:33:34 INFO - VRAM (Dynamic, Max): 1536 MB 14:33:34 INFO - Vendor: Intel (0x8086) 14:33:34 INFO - Device ID: 0x0a2e 14:33:34 INFO - Revision ID: 0x0009 14:33:34 INFO - Displays: 14:33:34 INFO - Display: 14:33:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:33:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:33:34 INFO - Display Serial Number: 1600x1200 60 14:33:34 INFO - Main Display: Yes 14:33:34 INFO - Mirror: Off 14:33:34 INFO - Online: Yes 14:33:34 INFO - Rotation: Supported 14:33:34 INFO - Return code: 0 14:33:34 INFO - Running post-action listener: _resource_record_post_action 14:33:34 INFO - [mozharness: 2016-11-14 22:33:34.189837Z] Finished run-tests step (success) 14:33:34 INFO - Running post-run listener: _resource_record_post_run 14:33:34 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 356352; Write bytes: 698573312; Read time: 6; Write time: 2346 14:33:34 INFO - TinderboxPrint: CPU usage
53.4% 14:33:34 INFO - TinderboxPrint: I/O read bytes / time
356,352 / 6 14:33:34 INFO - TinderboxPrint: I/O write bytes / time
698,573,312 / 2,346 14:33:34 INFO - TinderboxPrint: CPU idle
2,263.6 (46.6%) 14:33:34 INFO - TinderboxPrint: CPU system
969.6 (19.9%) 14:33:34 INFO - TinderboxPrint: CPU user
1,628.5 (33.5%) 14:33:34 INFO - TinderboxPrint: Swap in / out
398,483,456 / 0 14:33:34 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 380756480; Write bytes: 375727616; Read time: 28100; Write time: 1023 14:33:34 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 356352; Write bytes: 297081856; Read time: 6; Write time: 1267 14:33:34 INFO - Running post-run listener: _upload_blobber_files 14:33:34 INFO - Blob upload gear active. 14:33:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:33:34 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 14:33:34 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:33:34 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:33:35 INFO - (blobuploader) - INFO - Open directory for files ... 14:33:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:33:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:33:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:33:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:33:36 INFO - (blobuploader) - INFO - Done attempting. 14:33:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:33:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:33:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:33:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:33:37 INFO - (blobuploader) - INFO - Done attempting. 14:33:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:33:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:33:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:33:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:33:39 INFO - (blobuploader) - INFO - Done attempting. 14:33:39 INFO - (blobuploader) - INFO - Iteration through files over. 14:33:39 INFO - Return code: 0 14:33:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:33:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:33:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/11989303288d7c7b82240a02b9ec4ad3307811873e63effe5c6244352e4c55a00127ab8b93707571cc127a801237754f2f7b3c074c3f141b0c4c0630ba3ef88b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9f4ddebf61ba473ebd54a79c67348ea887fbb7546a6de270bc4901072ef927ab5d0f947bb39ff36cc262e6d9b5ec34e6ca97ffa7eb23b3f1eb4907b5803d06cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/db37907699146dccce26b7bb6db275e375841755b699187b242c0f0c02cd5067cfa09c9b9bdd21b37c31b009e504e0ca1d0161fd38dfc8dbc7b54b0c81b11836"} 14:33:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:33:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:33:39 INFO - Contents: 14:33:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/11989303288d7c7b82240a02b9ec4ad3307811873e63effe5c6244352e4c55a00127ab8b93707571cc127a801237754f2f7b3c074c3f141b0c4c0630ba3ef88b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9f4ddebf61ba473ebd54a79c67348ea887fbb7546a6de270bc4901072ef927ab5d0f947bb39ff36cc262e6d9b5ec34e6ca97ffa7eb23b3f1eb4907b5803d06cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/db37907699146dccce26b7bb6db275e375841755b699187b242c0f0c02cd5067cfa09c9b9bdd21b37c31b009e504e0ca1d0161fd38dfc8dbc7b54b0c81b11836"} 14:33:39 INFO - Running post-run listener: copy_logs_to_upload_dir 14:33:39 INFO - Copying logs to upload dir... 14:33:39 INFO - mkdir: /builds/slave/test/build/upload/logs 14:33:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.143994 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-14 14:33:39.343058) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 14:33:39.347637) ========= 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.ioL8MabQ4N/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.cMGegJizu2/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/11989303288d7c7b82240a02b9ec4ad3307811873e63effe5c6244352e4c55a00127ab8b93707571cc127a801237754f2f7b3c074c3f141b0c4c0630ba3ef88b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9f4ddebf61ba473ebd54a79c67348ea887fbb7546a6de270bc4901072ef927ab5d0f947bb39ff36cc262e6d9b5ec34e6ca97ffa7eb23b3f1eb4907b5803d06cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/db37907699146dccce26b7bb6db275e375841755b699187b242c0f0c02cd5067cfa09c9b9bdd21b37c31b009e504e0ca1d0161fd38dfc8dbc7b54b0c81b11836"} build_url:https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011129 build_url: 'https://queue.taskcluster.net/v1/task/X2vAJCyTTpqA09z9c9DZ7Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/11989303288d7c7b82240a02b9ec4ad3307811873e63effe5c6244352e4c55a00127ab8b93707571cc127a801237754f2f7b3c074c3f141b0c4c0630ba3ef88b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9f4ddebf61ba473ebd54a79c67348ea887fbb7546a6de270bc4901072ef927ab5d0f947bb39ff36cc262e6d9b5ec34e6ca97ffa7eb23b3f1eb4907b5803d06cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/db37907699146dccce26b7bb6db275e375841755b699187b242c0f0c02cd5067cfa09c9b9bdd21b37c31b009e504e0ca1d0161fd38dfc8dbc7b54b0c81b11836"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 14:33:39.383237) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 14:33:39.383578) ========= 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.ioL8MabQ4N/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.cMGegJizu2/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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.005356 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 14:33:39.433192) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 14:33:39.433506) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 14:33:39.433877) ========= ========= Total master_lag: 0.31 =========