builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0069 starttime: 1481922667.7 results: success (0) buildid: 20161216104749 builduid: dc493beccbaa4294995ea5b219685584 revision: 253b34072aeb84b850a7fa014875b4d97f08cbb2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 13:11:07.704420) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 13:11:07.704862) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 13:11:07.705142) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 13:11:07.732210) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 13:11:07.732562) ========= 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.WbQkiQST7J/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.x9egy9eV2G/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-16 13:11:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 11.6M=0.001s 2016-12-16 13:11:07 (11.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107219 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 13:11:07.936866) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 13:11:07.937187) ========= 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.WbQkiQST7J/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.x9egy9eV2G/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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.026373 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 13:11:07.985378) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-16 13:11:07.985728) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 253b34072aeb84b850a7fa014875b4d97f08cbb2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 253b34072aeb84b850a7fa014875b4d97f08cbb2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WbQkiQST7J/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.x9egy9eV2G/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-16 13:11:08,081 truncating revision to first 12 chars 2016-12-16 13:11:08,082 Setting DEBUG logging. 2016-12-16 13:11:08,082 attempt 1/10 2016-12-16 13:11:08,082 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/253b34072aeb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-16 13:11:09,093 unpacking tar archive at: mozilla-aurora-253b34072aeb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.281923 ========= master_lag: 0.46 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-16 13:11:09.728745) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 13:11:09.729190) ========= script_repo_revision: 253b34072aeb84b850a7fa014875b4d97f08cbb2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 13:11:09.729626) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 13:11:09.729921) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 13:11:09.750041) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-16 13:11:09.750395) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WbQkiQST7J/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.x9egy9eV2G/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 13:11:09 INFO - MultiFileLogger online at 20161216 13:11:09 in /builds/slave/test 13:11:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:11:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:11:09 INFO - {'append_to_log': False, 13:11:09 INFO - 'base_work_dir': '/builds/slave/test', 13:11:09 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:11:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:11:09 INFO - 'branch': 'Mozilla-Aurora', 13:11:09 INFO - 'buildbot_json_path': 'buildprops.json', 13:11:09 INFO - 'config_files': ('talos/mac_config.py',), 13:11:09 INFO - 'default_actions': ('clobber', 13:11:09 INFO - 'read-buildbot-config', 13:11:09 INFO - 'download-and-extract', 13:11:09 INFO - 'populate-webroot', 13:11:09 INFO - 'create-virtualenv', 13:11:09 INFO - 'install', 13:11:09 INFO - 'run-tests'), 13:11:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:11:09 INFO - 'download_minidump_stackwalk': True, 13:11:09 INFO - 'download_symbols': 'ondemand', 13:11:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:11:09 INFO - 'tooltool.py': '/tools/tooltool.py', 13:11:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:11:09 INFO - '/tools/misc-python/virtualenv.py')}, 13:11:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:11:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:11:09 INFO - 'installer_path': 'installer.exe', 13:11:09 INFO - 'log_level': 'info', 13:11:09 INFO - 'log_name': 'talos', 13:11:09 INFO - 'log_to_console': True, 13:11:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:11:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:11:09 INFO - 'opt_config_files': (), 13:11:09 INFO - 'pip_index': False, 13:11:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:11:09 INFO - 'cmd': ('bash', 13:11:09 INFO - '-c', 13:11:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:11:09 INFO - 'enabled': True, 13:11:09 INFO - 'halt_on_failure': False, 13:11:09 INFO - 'name': 'check_screen_resolution'},), 13:11:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:11:09 INFO - 'cmd': ('bash', 13:11:09 INFO - '-c', 13:11:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:11:09 INFO - 'enabled': True, 13:11:09 INFO - 'halt_on_failure': False, 13:11:09 INFO - 'name': 'check_screen_resolution'},), 13:11:09 INFO - 'run_cmd_checks_enabled': True, 13:11:09 INFO - 'sps_profile': False, 13:11:09 INFO - 'sps_profile_interval': 0, 13:11:09 INFO - 'suite': 'dromaeojs-e10s', 13:11:09 INFO - 'system_bits': '32', 13:11:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:11:09 INFO - 'title': 't-yosemite-r7-0069', 13:11:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:11:09 INFO - 'use_talos_json': True, 13:11:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:11:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:11:09 INFO - 'work_dir': 'build'} 13:11:09 INFO - [mozharness: 2016-12-16 21:11:09.953335Z] Running clobber step. 13:11:09 INFO - Running pre-action listener: _resource_record_pre_action 13:11:09 INFO - Running main action method: clobber 13:11:09 INFO - rmtree: /builds/slave/test/build 13:11:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:11:12 INFO - Running post-action listener: _resource_record_post_action 13:11:12 INFO - [mozharness: 2016-12-16 21:11:12.434847Z] Finished clobber step (success) 13:11:12 INFO - [mozharness: 2016-12-16 21:11:12.435013Z] Running read-buildbot-config step. 13:11:12 INFO - Running pre-action listener: _resource_record_pre_action 13:11:12 INFO - Running main action method: read_buildbot_config 13:11:12 INFO - Using buildbot properties: 13:11:12 INFO - { 13:11:12 INFO - "project": "", 13:11:12 INFO - "product": "firefox", 13:11:12 INFO - "script_repo_revision": "production", 13:11:12 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 13:11:12 INFO - "repository": "", 13:11:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 13:11:12 INFO - "buildid": "20161216104749", 13:11:12 INFO - "pgo_build": "False", 13:11:12 INFO - "basedir": "/builds/slave/test", 13:11:12 INFO - "buildnumber": 65, 13:11:12 INFO - "slavename": "t-yosemite-r7-0069", 13:11:12 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 13:11:12 INFO - "platform": "yosemite_r7", 13:11:12 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:11:12 INFO - "branch": "mozilla-aurora", 13:11:12 INFO - "repo_path": "releases/mozilla-aurora", 13:11:12 INFO - "revision": "253b34072aeb84b850a7fa014875b4d97f08cbb2", 13:11:12 INFO - "stage_platform": "macosx64", 13:11:12 INFO - "builduid": "dc493beccbaa4294995ea5b219685584", 13:11:12 INFO - "slavebuilddir": "test" 13:11:12 INFO - } 13:11:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 13:11:12 INFO - Running post-action listener: _resource_record_post_action 13:11:12 INFO - [mozharness: 2016-12-16 21:11:12.438343Z] Finished read-buildbot-config step (success) 13:11:12 INFO - [mozharness: 2016-12-16 21:11:12.438451Z] Running download-and-extract step. 13:11:12 INFO - Running pre-action listener: _resource_record_pre_action 13:11:12 INFO - Running main action method: download_and_extract 13:11:12 INFO - mkdir: /builds/slave/test/build/tests 13:11: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')]} 13:11:12 INFO - trying https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 13:11:12 INFO - Downloading https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 13:11:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 13:11:13 INFO - Downloaded 1199 bytes. 13:11:13 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 13:11:13 INFO - Using the following test package requirements: 13:11:13 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 13:11:13 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:11:13 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 13:11:13 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:11:13 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 13:11:13 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:11:13 INFO - u'jsshell-mac.zip'], 13:11:13 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:11:13 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 13:11:13 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 13:11:13 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:11:13 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 13:11:13 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:11:13 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 13:11:13 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:11:13 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 13:11:13 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:11:13 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 13:11:13 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:11:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 13:11:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:11:13 INFO - Fetch https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 13:11:14 INFO - Http code: 200 13:11:14 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 13:11:14 INFO - x-amz-cf-id: qw5DZ8xlSfLsXZwOAiC8E9i-IqTzq73X27_fcYElawYF1fJUxfP07A== 13:11:14 INFO - x-amz-version-id: iSYylzwaqI0mwUg3YK9KlW2FN_nZ4fbO 13:11:14 INFO - Expected file size: 16280454 13:11:14 INFO - Obtained file size: 16280454 13:11:21 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 13:11:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 13:11:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:11:21 INFO - Fetch https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 13:11:21 INFO - Http code: 200 13:11:21 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 13:11:21 INFO - x-amz-cf-id: bJMUCyd8KoI2CvAS53D4OzxMyDfG0zG10-BdIdHDK9DLSp9kEniovA== 13:11:21 INFO - x-amz-version-id: iSYylzwaqI0mwUg3YK9KlW2FN_nZ4fbO 13:11:22 INFO - Expected file size: 16280454 13:11:22 INFO - Obtained file size: 16280454 13:11:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 13:11:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:11:28 INFO - Fetch https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 13:11:29 INFO - Http code: 200 13:11:29 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 13:11:29 INFO - x-amz-cf-id: kTEybhOEMNHBRvq8I4TKBVh78G-4TUXB2UfbvbQ_ce8GDCLQLxAfbg== 13:11:29 INFO - x-amz-version-id: N.8uH4sZDQsNVHG485Jow1JUFOgIONeu 13:11:29 INFO - Expected file size: 14409038 13:11:29 INFO - Obtained file size: 14409038 13:11:30 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')]} 13:11:30 INFO - trying https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 13:11:30 INFO - Downloading https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 13:11:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 13:11:32 INFO - Downloaded 109729813 bytes. 13:11:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 13:11:32 INFO - mkdir: /builds/slave/test/properties 13:11:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:11:32 INFO - Writing to file /builds/slave/test/properties/build_url 13:11:32 INFO - Contents: 13:11:32 INFO - build_url:https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 13:11:33 INFO - Running post-action listener: _resource_record_post_action 13:11:33 INFO - Running post-action listener: set_extra_try_arguments 13:11:33 INFO - [mozharness: 2016-12-16 21:11:33.568323Z] Finished download-and-extract step (success) 13:11:33 INFO - [mozharness: 2016-12-16 21:11:33.568468Z] Running populate-webroot step. 13:11:33 INFO - Running pre-action listener: _resource_record_pre_action 13:11:33 INFO - Running main action method: populate_webroot 13:11:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:11:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:11:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:11:33 INFO - '--tppagecycles', 13:11:33 INFO - '7'], 13:11:33 INFO - 'tests': ['tsvgm']}}, 13:11:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:11:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:11:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:11:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:11:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:11:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:11:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:33 INFO - 'talos_options': ['--disable-e10s'], 13:11:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:11:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:11:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:33 INFO - 'talos_options': ['--disable-e10s'], 13:11:33 INFO - 'tests': ['damp', 'tps']}, 13:11:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:33 INFO - 'tests': ['damp', 'tps']}, 13:11:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:11:33 INFO - 'tests': ['dromaeo_dom']}, 13:11:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:11:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:11:33 INFO - 'tests': ['basic_compositor_video']}, 13:11:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:11:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:11:33 INFO - 'tests': ['a11yr', 13:11:33 INFO - 'ts_paint', 13:11:33 INFO - 'tpaint', 13:11:33 INFO - 'sessionrestore', 13:11:33 INFO - 'sessionrestore_no_auto_restore', 13:11:33 INFO - 'tabpaint']}, 13:11:33 INFO - 'other-e10s': {'tests': ['a11yr', 13:11:33 INFO - 'ts_paint', 13:11:33 INFO - 'tpaint', 13:11:33 INFO - 'sessionrestore', 13:11:33 INFO - 'sessionrestore_no_auto_restore', 13:11:33 INFO - 'tabpaint']}, 13:11:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:11:33 INFO - 'ts_paint', 13:11:33 INFO - 'tpaint', 13:11:33 INFO - 'sessionrestore', 13:11:33 INFO - 'sessionrestore_no_auto_restore', 13:11:33 INFO - 'tabpaint']}, 13:11:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:11:33 INFO - 'ts_paint', 13:11:33 INFO - 'tpaint', 13:11:33 INFO - 'sessionrestore', 13:11:33 INFO - 'sessionrestore_no_auto_restore', 13:11:33 INFO - 'tabpaint']}, 13:11:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:11:33 INFO - 'tests': ['a11yr', 13:11:33 INFO - 'ts_paint', 13:11:33 INFO - 'tpaint', 13:11:33 INFO - 'sessionrestore', 13:11:33 INFO - 'sessionrestore_no_auto_restore', 13:11:33 INFO - 'tabpaint']}, 13:11:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:11:33 INFO - 'tests': ['a11yr', 13:11:33 INFO - 'ts_paint', 13:11:33 INFO - 'tpaint', 13:11:33 INFO - 'sessionrestore', 13:11:33 INFO - 'sessionrestore_no_auto_restore', 13:11:33 INFO - 'tabpaint']}, 13:11:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:11:33 INFO - 'tests': ['tsvgx', 13:11:33 INFO - 'tsvgr_opacity', 13:11:33 INFO - 'tart', 13:11:33 INFO - 'tscrollx', 13:11:33 INFO - 'cart']}, 13:11:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:11:33 INFO - 'tsvgr_opacity', 13:11:33 INFO - 'tart', 13:11:33 INFO - 'tscrollx', 13:11:33 INFO - 'cart']}, 13:11:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:33 INFO - 'talos_options': ['--disable-e10s'], 13:11:33 INFO - 'tests': ['tp5o']}, 13:11:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:33 INFO - 'tests': ['tp5o']}, 13:11:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:33 INFO - 'talos_options': ['--disable-e10s', 13:11:33 INFO - '--xperf_path', 13:11:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:11:33 INFO - 'tests': ['tp5n']}, 13:11:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:33 INFO - 'talos_options': ['--xperf_path', 13:11:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:11:33 INFO - 'tests': ['tp5n']}}, 13:11:33 INFO - 'talos.zip': {'path': '', 13:11:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:11:33 INFO - Running post-action listener: _resource_record_post_action 13:11:33 INFO - [mozharness: 2016-12-16 21:11:33.587503Z] Finished populate-webroot step (success) 13:11:33 INFO - [mozharness: 2016-12-16 21:11:33.587631Z] Running create-virtualenv step. 13:11:33 INFO - Running pre-action listener: _resource_record_pre_action 13:11:33 INFO - Running main action method: create_virtualenv 13:11:33 INFO - Creating virtualenv /builds/slave/test/build/venv 13:11:33 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 13:11:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:11:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:11:33 INFO - Using real prefix '/tools/python27' 13:11:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:11:34 INFO - Installing distribute.............................................................................................................................................................................................done. 13:11:37 INFO - Installing pip.................done. 13:11:37 INFO - Return code: 0 13:11:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:11:37 INFO - Reading from file tmpfile_stdout 13:11:37 INFO - Output received: 13:11:37 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) 13:11:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11065e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc8dd3cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472d30>, '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 0x1102d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046ab20>, '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.WbQkiQST7J/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.x9egy9eV2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 13:11:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:11:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WbQkiQST7J/Render', 13:11:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:37 INFO - 'HOME': '/Users/cltbld', 13:11:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:37 INFO - 'LOGNAME': 'cltbld', 13:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:37 INFO - 'MOZ_NO_REMOTE': '1', 13:11:37 INFO - 'NO_EM_RESTART': '1', 13:11:37 INFO - 'PAGER': '/bin/cat', 13:11:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:37 INFO - 'PWD': '/builds/slave/test', 13:11:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:37 INFO - 'SHELL': '/bin/bash', 13:11:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x9egy9eV2G/Listeners', 13:11:37 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 13:11:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:37 INFO - 'USER': 'cltbld', 13:11:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:37 INFO - 'XPC_FLAGS': '0x0', 13:11:37 INFO - 'XPC_SERVICE_NAME': '0', 13:11:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:11:37 INFO - Cleaning up... 13:11:37 INFO - Return code: 0 13:11:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11065e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc8dd3cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472d30>, '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 0x1102d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046ab20>, '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.WbQkiQST7J/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.x9egy9eV2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 13:11:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:11:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WbQkiQST7J/Render', 13:11:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:37 INFO - 'HOME': '/Users/cltbld', 13:11:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:37 INFO - 'LOGNAME': 'cltbld', 13:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:37 INFO - 'MOZ_NO_REMOTE': '1', 13:11:37 INFO - 'NO_EM_RESTART': '1', 13:11:37 INFO - 'PAGER': '/bin/cat', 13:11:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:37 INFO - 'PWD': '/builds/slave/test', 13:11:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:37 INFO - 'SHELL': '/bin/bash', 13:11:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x9egy9eV2G/Listeners', 13:11:37 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 13:11:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:37 INFO - 'USER': 'cltbld', 13:11:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:37 INFO - 'XPC_FLAGS': '0x0', 13:11:37 INFO - 'XPC_SERVICE_NAME': '0', 13:11:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:38 INFO - Downloading/unpacking psutil>=3.1.1 13:11:38 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 13:11:38 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 13:11:38 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 13:11:38 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 13:11:38 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 13:11:38 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 13:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:11:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:11:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:11:41 INFO - Installing collected packages: psutil 13:11:41 INFO - Running setup.py install for psutil 13:11:41 INFO - building 'psutil._psutil_osx' extension 13:11:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:11:42 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 13:11:42 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 13:11:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 13:11:42 INFO - building 'psutil._psutil_posix' extension 13:11:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 13:11:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:11:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:11:42 INFO - ^ 13:11:42 INFO - 1 warning generated. 13:11:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:11:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:11:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:11:42 INFO - Successfully installed psutil 13:11:42 INFO - Cleaning up... 13:11:42 INFO - Return code: 0 13:11:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11065e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc8dd3cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472d30>, '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 0x1102d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046ab20>, '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.WbQkiQST7J/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.x9egy9eV2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 13:11:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:11:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WbQkiQST7J/Render', 13:11:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:42 INFO - 'HOME': '/Users/cltbld', 13:11:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:42 INFO - 'LOGNAME': 'cltbld', 13:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:42 INFO - 'MOZ_NO_REMOTE': '1', 13:11:42 INFO - 'NO_EM_RESTART': '1', 13:11:42 INFO - 'PAGER': '/bin/cat', 13:11:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:42 INFO - 'PWD': '/builds/slave/test', 13:11:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:42 INFO - 'SHELL': '/bin/bash', 13:11:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x9egy9eV2G/Listeners', 13:11:42 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 13:11:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:42 INFO - 'USER': 'cltbld', 13:11:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:42 INFO - 'XPC_FLAGS': '0x0', 13:11:42 INFO - 'XPC_SERVICE_NAME': '0', 13:11:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:11:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:11:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:11:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:11:45 INFO - Installing collected packages: mozsystemmonitor 13:11:45 INFO - Running setup.py install for mozsystemmonitor 13:11:45 INFO - Successfully installed mozsystemmonitor 13:11:45 INFO - Cleaning up... 13:11:46 INFO - Return code: 0 13:11:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11065e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc8dd3cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472d30>, '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 0x1102d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046ab20>, '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.WbQkiQST7J/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.x9egy9eV2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 13:11:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:11:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:11:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WbQkiQST7J/Render', 13:11:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:46 INFO - 'HOME': '/Users/cltbld', 13:11:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:46 INFO - 'LOGNAME': 'cltbld', 13:11:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:46 INFO - 'MOZ_NO_REMOTE': '1', 13:11:46 INFO - 'NO_EM_RESTART': '1', 13:11:46 INFO - 'PAGER': '/bin/cat', 13:11:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:46 INFO - 'PWD': '/builds/slave/test', 13:11:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:46 INFO - 'SHELL': '/bin/bash', 13:11:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x9egy9eV2G/Listeners', 13:11:46 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 13:11:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:46 INFO - 'USER': 'cltbld', 13:11:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:46 INFO - 'XPC_FLAGS': '0x0', 13:11:46 INFO - 'XPC_SERVICE_NAME': '0', 13:11:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:46 INFO - Downloading/unpacking jsonschema==2.5.1 13:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:11:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:49 INFO - Downloading functools32-3.2.3-2.tar.gz 13:11:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:11:49 INFO - warning: no files found matching '*.txt' 13:11:49 INFO - no previously-included directories found matching 'build' 13:11:49 INFO - no previously-included directories found matching 'dist' 13:11:49 INFO - no previously-included directories found matching '.git*' 13:11:49 INFO - Installing collected packages: jsonschema, functools32 13:11:49 INFO - Running setup.py install for functools32 13:11:49 INFO - warning: no files found matching '*.txt' 13:11:49 INFO - no previously-included directories found matching 'build' 13:11:49 INFO - no previously-included directories found matching 'dist' 13:11:49 INFO - no previously-included directories found matching '.git*' 13:11:49 INFO - Successfully installed jsonschema functools32 13:11:49 INFO - Cleaning up... 13:11:49 INFO - Return code: 0 13:11:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11065e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc8dd3cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472d30>, '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 0x1102d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046ab20>, '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.WbQkiQST7J/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.x9egy9eV2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 13:11:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:11:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WbQkiQST7J/Render', 13:11:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:49 INFO - 'HOME': '/Users/cltbld', 13:11:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:49 INFO - 'LOGNAME': 'cltbld', 13:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:49 INFO - 'MOZ_NO_REMOTE': '1', 13:11:49 INFO - 'NO_EM_RESTART': '1', 13:11:49 INFO - 'PAGER': '/bin/cat', 13:11:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:49 INFO - 'PWD': '/builds/slave/test', 13:11:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:49 INFO - 'SHELL': '/bin/bash', 13:11:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x9egy9eV2G/Listeners', 13:11:49 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 13:11:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:49 INFO - 'USER': 'cltbld', 13:11:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:49 INFO - 'XPC_FLAGS': '0x0', 13:11:49 INFO - 'XPC_SERVICE_NAME': '0', 13:11:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:11:50 INFO - Cleaning up... 13:11:50 INFO - Return code: 0 13:11:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:11:50 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')]} 13:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:50 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')]} 13:11:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:50 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 0x11065e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc8dd3cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472d30>, '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 0x1102d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046ab20>, '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.WbQkiQST7J/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.x9egy9eV2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 13:11:50 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 13:11:50 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 13:11:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WbQkiQST7J/Render', 13:11:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:50 INFO - 'HOME': '/Users/cltbld', 13:11:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:50 INFO - 'LOGNAME': 'cltbld', 13:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:50 INFO - 'MOZ_NO_REMOTE': '1', 13:11:50 INFO - 'NO_EM_RESTART': '1', 13:11:50 INFO - 'PAGER': '/bin/cat', 13:11:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:50 INFO - 'PWD': '/builds/slave/test', 13:11:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:50 INFO - 'SHELL': '/bin/bash', 13:11:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x9egy9eV2G/Listeners', 13:11:50 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 13:11:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:50 INFO - 'USER': 'cltbld', 13:11:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:50 INFO - 'XPC_FLAGS': '0x0', 13:11:50 INFO - 'XPC_SERVICE_NAME': '0', 13:11:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:50 INFO - Downloading/unpacking blobuploader==1.2.4 13:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:52 INFO - Downloading blobuploader-1.2.4.tar.gz 13:11:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:11:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:11:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:11:53 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 13:11:53 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 13:11:53 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 13:11:53 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 13:11:53 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 13:11:53 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 13:11:54 INFO - Downloading docopt-0.6.1.tar.gz 13:11:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:11:54 INFO - Installing collected packages: blobuploader, requests, docopt 13:11:54 INFO - Running setup.py install for blobuploader 13:11:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:11:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:11:54 INFO - Running setup.py install for requests 13:11:54 INFO - Running setup.py install for docopt 13:11:54 INFO - Successfully installed blobuploader requests docopt 13:11:54 INFO - Cleaning up... 13:11:55 INFO - Return code: 0 13:11:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11:55 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')]} 13:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:55 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')]} 13:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:55 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 0x11065e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc8dd3cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472d30>, '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 0x1102d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046ab20>, '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.WbQkiQST7J/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.x9egy9eV2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 13:11:55 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 13:11:55 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 13:11:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WbQkiQST7J/Render', 13:11:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:55 INFO - 'HOME': '/Users/cltbld', 13:11:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:55 INFO - 'LOGNAME': 'cltbld', 13:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:55 INFO - 'MOZ_NO_REMOTE': '1', 13:11:55 INFO - 'NO_EM_RESTART': '1', 13:11:55 INFO - 'PAGER': '/bin/cat', 13:11:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:55 INFO - 'PWD': '/builds/slave/test', 13:11:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:55 INFO - 'SHELL': '/bin/bash', 13:11:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x9egy9eV2G/Listeners', 13:11:55 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 13:11:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:55 INFO - 'USER': 'cltbld', 13:11:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:55 INFO - 'XPC_FLAGS': '0x0', 13:11:55 INFO - 'XPC_SERVICE_NAME': '0', 13:11:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:11:55 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-CEdtUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:11:55 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-mN2qGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:11:55 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-3Xf_Nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:11:55 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-w3Cynz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:11:55 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-Wk6Eq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:11:55 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-Sh_twl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:11:55 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-48nZoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:11:56 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-tRpXD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:11:56 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-AHkKjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:11:56 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-NKaFcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:11:56 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-uXEZ84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:11:56 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-6XAEwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:11:56 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-YOvny8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:11:56 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-UpcGDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:56 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-7vjYpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:11:56 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-sPT0d_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:11:57 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-8bTMke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:11:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:11:57 INFO - Running setup.py install for manifestparser 13:11:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:11:57 INFO - Running setup.py install for mozcrash 13:11:57 INFO - Running setup.py install for mozdebug 13:11:57 INFO - Running setup.py install for mozdevice 13:11:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:11:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:11:57 INFO - Running setup.py install for mozfile 13:11:57 INFO - Running setup.py install for mozhttpd 13:11:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:11:58 INFO - Running setup.py install for mozinfo 13:11:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:11:58 INFO - Running setup.py install for mozInstall 13:11:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:11:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:11:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:11:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:11:58 INFO - Running setup.py install for mozleak 13:11:58 INFO - Running setup.py install for mozlog 13:11:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:11:58 INFO - Running setup.py install for moznetwork 13:11:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:11:58 INFO - Running setup.py install for mozprocess 13:11:58 INFO - Running setup.py install for mozprofile 13:11:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:11:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:11:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:11:59 INFO - Running setup.py install for mozrunner 13:11:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:11:59 INFO - Running setup.py install for mozscreenshot 13:11:59 INFO - Running setup.py install for moztest 13:11:59 INFO - Running setup.py install for mozversion 13:11:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:11:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:11:59 INFO - Cleaning up... 13:11:59 INFO - Return code: 0 13:11:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:59 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 0x11065e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc8dd3cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472d30>, '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 0x1102d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046ab20>, '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.WbQkiQST7J/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.x9egy9eV2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 13:11:59 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 13:11:59 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 13:11:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WbQkiQST7J/Render', 13:11:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:59 INFO - 'HOME': '/Users/cltbld', 13:11:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:59 INFO - 'LOGNAME': 'cltbld', 13:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:59 INFO - 'MOZ_NO_REMOTE': '1', 13:11:59 INFO - 'NO_EM_RESTART': '1', 13:11:59 INFO - 'PAGER': '/bin/cat', 13:11:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:59 INFO - 'PWD': '/builds/slave/test', 13:11:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:59 INFO - 'SHELL': '/bin/bash', 13:11:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x9egy9eV2G/Listeners', 13:11:59 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 13:11:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:59 INFO - 'USER': 'cltbld', 13:11:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:59 INFO - 'XPC_FLAGS': '0x0', 13:11:59 INFO - 'XPC_SERVICE_NAME': '0', 13:11:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:11:59 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-i8I77G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:12:00 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)) 13:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:12:00 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-MDSZ7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:12:00 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)) 13:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:12:00 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-30mOAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:12:00 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)) 13:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:12:00 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-pb7Ycy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:12:00 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)) 13:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:12:00 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-QA2J6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:12:00 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)) 13:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:12:00 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-xqxl_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:12:00 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)) 13:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:12:00 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-oxl8Rh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:12:00 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)) 13:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:12:00 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-edOvsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:12:00 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)) 13:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:12:00 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-LE9clE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:12:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:12:00 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-7ZD0b3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:12:01 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)) 13:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:12:01 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-Rwu999-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:12:01 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)) 13:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:12:01 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-IyOr1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:12:01 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)) 13:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:12:01 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-RZhXa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:12:01 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)) 13:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:12:01 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-X84TN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:12:01 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)) 13:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:01 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-Lo4KA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:01 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)) 13:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:12:01 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-DW2QGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:12:01 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)) 13:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:12:01 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-xq3M0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:12:01 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)) 13:12:01 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)) 13:12:01 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)) 13:12:01 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)) 13:12:01 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)) 13:12:01 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)) 13:12:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:12:01 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 13:12:01 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 13:12:01 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 13:12:01 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 13:12:01 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 13:12:01 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 13:12:04 INFO - Downloading blessings-1.6.tar.gz 13:12:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:12:04 INFO - Installing collected packages: blessings 13:12:04 INFO - Running setup.py install for blessings 13:12:04 INFO - Successfully installed blessings 13:12:04 INFO - Cleaning up... 13:12:04 INFO - Return code: 0 13:12:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:12:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:12:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:12:05 INFO - Reading from file tmpfile_stdout 13:12:05 INFO - Current package versions: 13:12:05 INFO - blessings == 1.6 13:12:05 INFO - blobuploader == 1.2.4 13:12:05 INFO - docopt == 0.6.1 13:12:05 INFO - functools32 == 3.2.3-2 13:12:05 INFO - jsonschema == 2.5.1 13:12:05 INFO - manifestparser == 1.1 13:12:05 INFO - mozInstall == 1.12 13:12:05 INFO - mozcrash == 1.0 13:12:05 INFO - mozdebug == 0.1 13:12:05 INFO - mozdevice == 0.48 13:12:05 INFO - mozfile == 1.2 13:12:05 INFO - mozhttpd == 0.7 13:12:05 INFO - mozinfo == 0.9 13:12:05 INFO - mozleak == 0.1 13:12:05 INFO - mozlog == 3.4 13:12:05 INFO - moznetwork == 0.27 13:12:05 INFO - mozprocess == 0.23 13:12:05 INFO - mozprofile == 0.28 13:12:05 INFO - mozrunner == 6.13 13:12:05 INFO - mozscreenshot == 0.1 13:12:05 INFO - mozsystemmonitor == 0.3 13:12:05 INFO - moztest == 0.7 13:12:05 INFO - mozversion == 1.4 13:12:05 INFO - psutil == 3.1.1 13:12:05 INFO - requests == 1.2.3 13:12:05 INFO - wsgiref == 0.1.2 13:12:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:12:05 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')]} 13:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:05 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')]} 13:12:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:05 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 0x11065e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc8dd3cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472d30>, '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 0x1102d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046ab20>, '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.WbQkiQST7J/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.x9egy9eV2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 13:12:05 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 13:12:05 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 13:12:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WbQkiQST7J/Render', 13:12:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:05 INFO - 'HOME': '/Users/cltbld', 13:12:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:05 INFO - 'LOGNAME': 'cltbld', 13:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:05 INFO - 'MOZ_NO_REMOTE': '1', 13:12:05 INFO - 'NO_EM_RESTART': '1', 13:12:05 INFO - 'PAGER': '/bin/cat', 13:12:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:05 INFO - 'PWD': '/builds/slave/test', 13:12:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:05 INFO - 'SHELL': '/bin/bash', 13:12:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x9egy9eV2G/Listeners', 13:12:05 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 13:12:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:05 INFO - 'USER': 'cltbld', 13:12:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:05 INFO - 'XPC_FLAGS': '0x0', 13:12:05 INFO - 'XPC_SERVICE_NAME': '0', 13:12:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:06 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)) 13:12:06 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)) 13:12:06 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)) 13:12:06 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)) 13:12:06 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)) 13:12:06 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)) 13:12:06 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)) 13:12:06 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)) 13:12:06 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)) 13:12:06 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)) 13:12:06 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)) 13:12:06 INFO - Cleaning up... 13:12:06 INFO - Return code: 0 13:12:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11065e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc8dd3cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472d30>, '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 0x1102d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046ab20>, '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.WbQkiQST7J/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.x9egy9eV2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 13:12:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:12:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:12:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WbQkiQST7J/Render', 13:12:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:06 INFO - 'HOME': '/Users/cltbld', 13:12:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:06 INFO - 'LOGNAME': 'cltbld', 13:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:06 INFO - 'MOZ_NO_REMOTE': '1', 13:12:06 INFO - 'NO_EM_RESTART': '1', 13:12:06 INFO - 'PAGER': '/bin/cat', 13:12:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:06 INFO - 'PWD': '/builds/slave/test', 13:12:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:06 INFO - 'SHELL': '/bin/bash', 13:12:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x9egy9eV2G/Listeners', 13:12:06 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 13:12:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:06 INFO - 'USER': 'cltbld', 13:12:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:06 INFO - 'XPC_FLAGS': '0x0', 13:12:06 INFO - 'XPC_SERVICE_NAME': '0', 13:12:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:12:06 INFO - Cleaning up... 13:12:06 INFO - Return code: 0 13:12:06 INFO - Running post-action listener: _resource_record_post_action 13:12:06 INFO - Running post-action listener: _start_resource_monitoring 13:12:06 INFO - Starting resource monitoring. 13:12:06 INFO - [mozharness: 2016-12-16 21:12:06.783410Z] Finished create-virtualenv step (success) 13:12:06 INFO - [mozharness: 2016-12-16 21:12:06.784341Z] Running install step. 13:12:06 INFO - Running pre-action listener: _resource_record_pre_action 13:12:06 INFO - Running main action method: install 13:12:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:12:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:12:07 INFO - Reading from file tmpfile_stdout 13:12:07 INFO - Detecting whether we're running mozinstall >=1.0... 13:12:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:12:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:12:07 INFO - Reading from file tmpfile_stdout 13:12:07 INFO - Output received: 13:12:07 INFO - Usage: mozinstall [options] installer 13:12:07 INFO - Options: 13:12:07 INFO - -h, --help show this help message and exit 13:12:07 INFO - -d DEST, --destination=DEST 13:12:07 INFO - Directory to install application into. [default: 13:12:07 INFO - "/builds/slave/test"] 13:12:07 INFO - --app=APP Application being installed. [default: firefox] 13:12:07 INFO - mkdir: /builds/slave/test/build/application 13:12:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:12:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 13:12:38 INFO - Reading from file tmpfile_stdout 13:12:38 INFO - Output received: 13:12:38 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:12:38 INFO - Running post-action listener: _resource_record_post_action 13:12:38 INFO - [mozharness: 2016-12-16 21:12:38.765488Z] Finished install step (success) 13:12:38 INFO - [mozharness: 2016-12-16 21:12:38.765635Z] Running run-tests step. 13:12:38 INFO - Running pre-action listener: _resource_record_pre_action 13:12:38 INFO - Running main action method: run_tests 13:12:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:12:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:12:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:12:38 INFO - 2016-12-16 13:12:38.780 screenresolution[1625:7733] starting screenresolution argv=screenresolution get 13:12:38 INFO - 2016-12-16 13:12:38.783 screenresolution[1625:7733] Display 0: 1600x1200x32@60 13:12:38 INFO - 2016-12-16 13:12:38.789 screenresolution[1626:7735] starting screenresolution argv=screenresolution list 13:12:38 INFO - Available Modes on Display 0 13:12:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:12:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:12:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:12:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:12:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:12:39 INFO - Intel Iris: 13:12:39 INFO - Chipset Model: Intel Iris 13:12:39 INFO - Type: GPU 13:12:39 INFO - Bus: Built-In 13:12:39 INFO - VRAM (Dynamic, Max): 1536 MB 13:12:39 INFO - Vendor: Intel (0x8086) 13:12:39 INFO - Device ID: 0x0a2e 13:12:39 INFO - Revision ID: 0x0009 13:12:39 INFO - Displays: 13:12:39 INFO - Display: 13:12:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:12:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:12:39 INFO - Display Serial Number: 1600x1200 60 13:12:39 INFO - Main Display: Yes 13:12:39 INFO - Mirror: Off 13:12:39 INFO - Online: Yes 13:12:39 INFO - Rotation: Supported 13:12:39 INFO - Return code: 0 13:12:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:12:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:12:39 INFO - Python 2.7.3 13:12:39 INFO - Return code: 0 13:12:39 INFO - grabbing minidump binary from tooltool 13:12:39 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')]} 13:12:39 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 0x110472d30>, '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 0x1102d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:12:39 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 13:12:39 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 13:12:39 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 13:12:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:12:39 INFO - Return code: 0 13:12:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:12:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:12:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:12:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:12:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:12:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 13:12:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0069 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 13:12:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WbQkiQST7J/Render', 13:12:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:39 INFO - 'HOME': '/Users/cltbld', 13:12:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:39 INFO - 'LOGNAME': 'cltbld', 13:12:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:12:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:39 INFO - 'MOZ_NO_REMOTE': '1', 13:12:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:12:39 INFO - 'NO_EM_RESTART': '1', 13:12:39 INFO - 'PAGER': '/bin/cat', 13:12:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:39 INFO - 'PWD': '/builds/slave/test', 13:12:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:12:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:39 INFO - 'SHELL': '/bin/bash', 13:12:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x9egy9eV2G/Listeners', 13:12:39 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 13:12:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:39 INFO - 'USER': 'cltbld', 13:12:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:39 INFO - 'XPC_FLAGS': '0x0', 13:12:39 INFO - 'XPC_SERVICE_NAME': '0', 13:12:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 13:12:39 INFO - mozversion application_buildid: 20161216104749 13:12:39 INFO - mozversion application_changeset: 253b34072aeb84b850a7fa014875b4d97f08cbb2 13:12:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:12:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:12:39 INFO - mozversion application_name: Firefox 13:12:39 INFO - mozversion application_remotingname: firefox-dev 13:12:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:12:39 INFO - mozversion application_vendor: Mozilla 13:12:39 INFO - mozversion application_version: 52.0a2 13:12:39 INFO - mozversion platform_buildid: 20161216104749 13:12:39 INFO - mozversion platform_changeset: 253b34072aeb84b850a7fa014875b4d97f08cbb2 13:12:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:12:39 INFO - mozversion platform_version: 52.0a2 13:12:39 INFO - using testdate: 1481922759 13:12:39 INFO - actual date: 1481922759 13:12:39 INFO - starting webserver on 'localhost:49241' 13:12:39 INFO - SUITE-START | Running 2 tests 13:12:39 INFO - TEST-START | dromaeo_css 13:12:39 INFO - operating with platform_type : mac_ 13:12:39 INFO - Initialising browser for dromaeo_css test... 13:12:39 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmpbIxtdc/profile http://localhost:49241/getInfo.html) 13:12:42 INFO - PROCESS | 1636 | 2016-12-16 13:12:42.595 plugin-container[1638:7913] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 13:12:42 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:12:42 INFO - PROCESS | 1636 | 2016-12-16 13:12:42.598 plugin-container[1638:7913] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 13:12:42 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:12:42 INFO - PROCESS | 1636 | 2016-12-16 13:12:42.598 plugin-container[1638:7913] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:12:42 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 13:12:42 INFO - PROCESS | 1636 | colorDepth:24 13:12:42 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 13:12:42 INFO - PROCESS | 1636 | __metrics 13:12:42 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 13:12:43 INFO - TEST-INFO | 1636: exit 0 13:12:43 INFO - Browser initialized. 13:12:43 INFO - Running cycle 1/1 for dromaeo_css test... 13:12:43 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmpbIxtdc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:12:46 INFO - PROCESS | 1639 | 2016-12-16 13:12:46.329 plugin-container[1640:8047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 13:12:46 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:12:46 INFO - PROCESS | 1639 | 2016-12-16 13:12:46.330 plugin-container[1640:8047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c03, name = 'com.apple.CFPasteboardClient' 13:12:46 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:12:46 INFO - PROCESS | 1639 | 2016-12-16 13:12:46.330 plugin-container[1640:8047] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:12:55 INFO - PROCESS | 1639 | RSS: Main: 229552128 13:12:55 INFO - PROCESS | 1639 | 13:13:45 INFO - PROCESS | 1639 | 1481922825455 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] 13:15:58 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 13:16:00 INFO - PROCESS | 1639 | RSS: Main: 235409408 13:16:00 INFO - PROCESS | 1639 | 13:19:03 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 13:19:04 INFO - PROCESS | 1639 | RSS: Main: 244445184 13:19:04 INFO - PROCESS | 1639 | 13:22:05 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 13:22:06 INFO - PROCESS | 1639 | RSS: Main: 247431168 13:22:06 INFO - PROCESS | 1639 | 13:25:08 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 13:25:09 INFO - PROCESS | 1639 | RSS: Main: 246681600 13:25:09 INFO - PROCESS | 1639 | 13:28:11 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 13:28:12 INFO - PROCESS | 1639 | RSS: Main: 250138624 13:28:12 INFO - PROCESS | 1639 | 13:31:15 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 13:31:15 INFO - PROCESS | 1639 | RSS: Main: 252370944 13:31:15 INFO - PROCESS | 1639 | 13:31:15 INFO - PROCESS | 1639 | __start_tp_report 13:31:15 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 13:31:15 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 13:31:15 INFO - PROCESS | 1639 | |i|pagename|runs| 13:31:15 INFO - PROCESS | 1639 | |0;dojo.html;2739.397332586831;3312.4700047992296;3327.550780644613;3335.966640333596;3356.7650264481495;5623.550115990721;5656.604037717357;5668.489835914762;5732.426757324265;5743.282089738783;5679.148127780835;5725.169850371693;5735.512481439083;5769.480746732793;5774.797882074117;5182.818601348944;5240.266362709223;5247.344081989751;5302.469753024705;5309.30978972733;5294.735263236823;5309.7610607522565;5323.174907889264;5344.652597581165;5389.865253368677;4529.275315949449;5227.059129356714;5315.176147697113;5333.226682131108;5334.1732031535175;4221.91556168878;5092.694438333675;5167.48325167484;5226.320578324831;5306.4958828911385;3995.900102497432;5164.819231326923;5189.221616757479;5236.947630523684;5270.262163297141;5799.159121927297;5804.564657650693;5819.388964158787;5834.679092649945;5854.092615644582;6072.240969878765;6166.352532984017;6224.688765561749;6229.87540249197;6274.717637706314;5092.719900405476;6161.445469907685;6178.721957511878;6186.783462578833;6226.813195604139;5820.214271073393;5832.300123985106;5886.175935369051;5901.557383196277;5905.852353691193;6133.212252453595;6267.2792628847355;6273.466755325757;6275.152854364603;6276.968615156895;6059.515238780887;6095.817125486243;6115.51416190291;6117.827404800917;6158.1686472326555;6206.224221972254;6230.158928544589;6231.719572619152;6250.531210159256;6277;6060.606060606046;6181;6188.690565471708;6195.721192546346;6280.591761535486;6110.103144028831;6117.736059405486;6184.196054512884;6232.034034724625;6247.125402443661;4127.607877251657;4602.987342784582;4918.877028074256;4957.801687932514;4986.351774269394;3587.9056887648994;4092.7544347339253;4251.341042138473;4479.238529449936;4490.483594386687;5663.745131469093;5804.1293805929445;5856.707164641751;5872.383399743051;5874.1776151338845;5148.227765835155;6080.331163572004;6204.875902482016;6213.596116252429;6250.1874756281395;4863.586595139453;5769;5777.90219858234;5871.91369596626;5986.610870293504;5408.161734931092;5416.756245968941;5438.700871452108;5445.618806683573;5447.836564903059;6109.586862300561;6263.311035785969;6270.9372906270355;6277.874442511215;6291.370862913672;6110.0727721002;6142.744345496893;6143.634700640375;6165.136880836776;6197.690115494297;6230.937690623035;6241.251049874044;6242.063690446288;6248.68756562161;6274.529410294337;6032.6983650818165;6165.136880836776;6170.969145154246;6178.289496707936;6184.845378865564;5514.972425137848;5978.312494063064;6124.319907527303;6144.310314663325;6232.691134861549;6148.47737942262;6177.722002509988;6180.6600636965395;6196.47329976957;6209.0686397039;6148.9692551536955;6156.199694039746;6159.476444502267;6198.318184999556;6205.751769929152;5650.773969041356;5870.412958704095;5870.823875283749;5909.793157239476;5967.134765459071;6037.305709843425;6062.393760624079;6095.695215239309;6105.236845394325;6116.113163591343;5895.174675545341;5922.318933322723;5926.407359264039;5928.614640048383;5937.673427961504 13:31:15 INFO - PROCESS | 1639 | |1;ext.html;430.9310510318349;489.4298142663797;528.0442399257345;533.6157966264288;553.007351803513;37585.81207093964;39379;39430.8028459858;39443.013924651896;39634.8018259909;40414.39378409326;40783.18433631326;40807.59192408075;40866;41123.9719007025;9249.907500924972;9428.481433521154;9450.14948654621;9460.24318054554;9478;9337.439753614804;9446.622135114587;9453.81092378152;9456.527173641325;9487.383320084207;9420.340576159637;9467.19528840046;9476.905230947707;9495.240380769557;9504.192143667797;9405.388649737779;9445.574949127305;9450.243980481546;9455.858162127573;9500.192483638899;9168.03735607765;9225.953870230673;9230.723078307661;9237.07629237071;9247.907520924839;38410.615893840986;38811;38855.41716874249;38878.41682374767;38879.611203887885;3122.4535706251313;3141.2146963259183;3213.951790723141;3401.9794061781367;3661.340958627446;29995.850020749975;30239.546406803915;30303.93936212244;30508.389832203455;30541.847290763402;3171.904842854718;3203.167176534118;3215.758818088653;3221.677832216783;3227.950915952325;356730.2163489192;378418;380963;381485;383663;60347.69826150841;60616.69691651602;60669.69665151734;60688;60712.69643651754;62429.68785156136;62902;63079;63103.68448157822;63576;14276.14343139415;15872.92063539675;16053.438129665406;16169.757453638205;16183;10393.48032598367;10734.73163170927;11967.982721468772;12361.814572781415;12487.937560312323;14850.925745371203;16711.913725607796;16996.32014719422;17095.829041709425;17227.96632202072;3159.4786860168274;6065.5450841187085;6109.755609775648;6771.593704377753;6985.825354366079;826.5536610230529;941.6233506597279;978.4569563891966;1060.3637817309552;1083.6809228251548;24400.511989760107;29881.850590746908;30042;30074.54888176679;30419.391612168074;995.5470261031213;1016.4358780876537;1091.317926296065;1131.145984781501;1155.1402242389745;14356.353964071643;15028.474003409829;15298.235088245543;15559.29983150761;15572.987755795837;681745.5912720504;689870;690595;692393;695243.5237823778;57624.13563796546;58084;58149;58279.125813112834;58330.4166958325;1329.1360615599938;1346.3268365817091;1378.5657517881834;1380.1236215003546;1381.6614929342375;29033.564496532148;29429.11712648624;30251.394972100876;30338.393232135677;30371.848140759153;50722.2391664118;54056.18915716189;55494.72252638711;55562;58305.70847145737;518092;520169;520214;523288;525888;41535.58464415317;41791.58208417999;42126.78936605297;42317.36523952082;42476.78761606172;45943.08113837771;46118.53881461143;47150;47192.7640361796;47220.7638961803;37311.2537749238;37969.43045854259;38239.61760382472;38328.6167138325;39645.80177099096;29521.409571808872;33563.83218083894;38747.806260968515;38771.41842872416;39851;36648.45027324645;37838;38580;38658.840234793;38732.80633596814;30942.38115237728;31378.843105784326;33988;34516.96449106531;34626.82686586652;24157.51684966326;25576;25800.870995644902;25808.18216137846;26145.607815882395 13:31:15 INFO - PROCESS | 1639 | |2;jquery.html;363.77809536182923;370.1855916982638;375.7895578476053;376.7682875031856;392.05905826017556;15274.465393711222;18853.622927541473;18885.43343699691;18945.71581426278;19050.61898762024;11374.829377559323;14680.11919284841;19096.904515477403;19147.521311967208;19619.215231390728;5246.973765131169;5464.907018596276;5478.287822583058;5484.533814625762;5484.808031718891;3849.615038496156;4825.372701548811;5383.353997520303;6070.089486577004;6100.572959892809;6122.816315510519;6142.78500252489;6143.754249830002;6148.87702245957;6174.10475481055;5714;6066.514678825705;6103.145559621678;6128.203333566652;6131.47882429994;5809.099589563624;5863.296404431496;5885.058390657474;5911.674857882857;5953.672548009858;13042.673933151653;13140.802887956688;13202;13273.800892986614;21076.683849742112;2285.211601997308;2375.572396968545;2405.9157929472385;2466.3217615155795;2894.72500112489;9524.428534287965;9972.301938864282;10011.849822252672;10140.442275674908;10176.491175441199;1854.7217917312375;1884.180381534037;1896.7344571759966;1908.809119088094;1913.9855876385539;93073.207803766;107447;109807;116994.83005170009;121190.39404803008;11156.553737850558;11277.887221127849;11372.090232781358;11810.350430726221;11949.402529873472;14167.433302667983;14220.146791192561;14400.783988240184;14445.49440769568;14456.638584035483;8463.407561470638;8559.57202139903;8591;8628.78428039304;8696.391252612382;8188.877166842502;8365.8326833463;8401.663933442594;8417.70538031166;8557.058723540405;4517.457905051415;4546.795394207269;4551.840685575989;4577.977110114428;4650.51169627191;1573.889827712049;1683.2004797720986;1689.6367281034636;1905.180772267938;1941.883486990785;866.757307953774;874.6851133591902;919.72868003939;934.5794392523392;971.7959228562081;20579.794202057787;20817.68773468399;21214.681779773317;21250.2562410318;21341.573168536543;923.7228831350568;925.9259259259243;936.2369668719972;991.9801603968025;1003.4731765823001;10463.110635595907;11171.66485005451;11255.380954047607;11320.886791132149;11323.150763692756;581047;581106;588580;593794;599457;138661.3066934659;144010.11979760346;144860;146691;148378.51621483863;964.9614015439445;1004.4124187350482;1016.4409574734015;1019.4342140112309;1020.7754294055296;18436.078196089875;19366.031698415303;19725.309614163434;19729.40811775649;19812.50468738293;35659.57361705503;42209.73370798881;42628.5737142637;52650.736746316026;55270.170947435014;79236;79503.20496794958;81868.59065704871;82888.58555707183;83142.58428707818;7071.752488662872;8028.036635603765;8147.226013528705;8219.54792486419;8275.710350137717;10858;11648.475818588015;13302.201867887958;13381.933090334485;13523.932380338036;10972.451377430936;11187.608433704781;11219.046381057702;11445.935931803919;11510.30938143714;5122.820701275437;10966.835497467704;11056.115510758958;11421.31472111676;11577.536898523964;9507.619695212114;10269.84595231087;10864.565417383532;11403.657890263305;11628.418579070843;6324.30432652417;7402.6668799903055;7418.072740907387;7533.2466753324225;7612.467127301035;10826.242163048511;11320.660380188607;11320.773584528428;11555.524539663153;11700.531978720755 13:31:15 INFO - PROCESS | 1639 | |3;mootools.html;1437.978430323545;1465.868071873531;1466.0250933129469;1627.7151498487763;1770.8760386772924;7347.448941329395;7775.30022297993;8098.635561399744;8575.31397488201;8577.742667719971;7809.414293927964;8307.00315962084;8342.582870856448;8415.24262816348;8460.703875364348;2292.346681195855;2332.24202134307;2332.941676458085;2335.369450248432;2335.9532809343805;2317.9652305215436;2350.048230466664;2374.050379848057;2375.5367703297866;2380.9166679166233;2313.9884300578474;2322.907083716649;2341.590221711202;2341.91803286885;2342.227065068532;1959.6472634925708;1963.9312624058089;1977.2091163534558;1978.4658142301641;1983.9008049597535;2313.4332088638225;2339.204670412051;2340.028888011473;2340.648902664597;2343.2267351773876;8356.373272004625;8413;8437.451565648213;8445.70440034602;8493.490390576524;4369.80335884886;5240.187770895517;5245.973770131163;5256.605754568384;5262.552683021948;3254.3491301739746;3368.8484018219237;3580.1944562473495;3581.83881725323;3596.4425514045365;1038.1072277841051;1050.753073027838;1076.4025965589094;1085.4626959654943;1106.3140852671315;10929.672109836718;10997.835032474519;11033.668989930235;11078.556857725682;11080.445977701163;3918.3338832398563;4207.894802629898;4613.469451013109;4687.5781179693995;4701.294805779092;7734.806629834186;8175.5503447309775;9489.667861624948;9556.474393908402;9562.187214086742;4415.381846541487;5201.115810312256;5208.374995000549;5220.190870415092;5230.790768369223;4195.832166713359;4412.7352358857925;4441.311596702516;4747.525247475225;4776.617870570346;3646.9452958205648;3704;3805.8858234253016;3816.809159542012;3825.1202223488754;1254.523281153156;1281.8461784585909;1291.2704322058007;1301.7786976214065;1308.3131356037966;845.3195177881864;851.7827953871847;857.8327226190958;878.6704985630389;884.5533005832013;5368.355797304349;5411.648242864201;5442.673439593558;5500.009998200362;5500.3949565547755;844.8732690096534;856.135303343615;877.5743764274322;880.1858281089966;910.7905181808221;3227.9193020174685;3290.6380298167182;3550.8934731958084;3626.492291079251;3743.7566558174176;9838.212942964547;10093.394396336244;10162.39025658463;10168;10178.796424071477;10042.648507302209;10050.748731281776;10052.59789608422;10123.7469063274;10158;7346.63266836656;7704.8844267336035;7837.882431763642;7878.842423151505;7878.842423151505;5413.160960051198;5414.160805075219;5657.12314591239;5674.602777805679;5743.741531631003;9201.585928633094;9297.86053209216;9345.018773029005;9408.482533460452;9417.759460472087;9329;9359.625614975324;9415.623375065195;9496.572654230715;9504.144626983605;6022.698865056817;6502.674866256573;6812.284710105566;6893.655317234218;7325.6337183141695;7879.724209652634;7978.760637180894;8054.798630034296;8058.3553315733425;8083.312918402002;6973.058637083929;7166.605836679033;7179.892301615376;7183.568985860865;7220.133583969957;6719.428848547928;6965.268646792217;7035.648217589202;7070.15158181001;7146.499745017799;6654.633995130314;6658.700358483783;6751.358620931003;6755.594664320157;6756.256811750802;4740.52594740537;4755.500672429345;4793.376861008047;4800.927986080282;4811.735354555393;4665.370175026464;4755.976220118877;4779.976100119617;4886.486918873609;4972.930819873744 13:31:15 INFO - PROCESS | 1639 | |4;prototype.html;281.8759745711887;290.21496851017974;301.11922626318017;311.3120004789415;314.878771672906;4399.802008909599;4618.6836751525825;5136.38363396392;5473.753681084351;5475.67145971242;4469.977650111745;4877.365942427479;5381.040427764724;5406.486383793543;5453.318335208099;4107.67138628909;4115.71190016699;4150.294449943509;4172.5201601815725;4183.644390226819;3190.8245046522434;4047.615476529725;4136.875893723193;4165;4175.958240417603;3599.982000089996;3955.7230993830444;4080.1843179785005;4103.317639767696;4151.979240103795;3719.8654410404815;3720.6837418819296;4084.530279017921;4106.40457133714;4129.327622312968;3137.4823154179535;3326.334733053399;3534.0771743364844;3544.379733546619;3556.3065202285566;4092.897677558085;5004.399472063339;5189.325387699575;5305.920411193835;5311.7344132793205;3851.6504931991462;4603.608693261077;4648.465426475965;4657.790399432034;4672.322513235562;5246.685198888041;5462.153366228241;5658.632188907708;5737.827865164052;5778.653280803165;3022.531507616323;3549.6272891346293;3643.67206951373;3692.4461330800327;3850.1519270750196;5988.790392336291;5998.460138587505;6013.849653758647;6036.008117321274;6036.456718895277;3314.4696848504122;3883.9611603884164;3892.260470510594;3896.8636097736444;3940.802959851996;3597.1546686528645;3735.1884336096973;3753.5361209128464;3914.316843757155;3963.0884896473394;4609.585137337655;4650.162970665313;4656.697314674535;4668.346431499524;4688.742119183409;5723.456271654186;5763.567732420001;5783.450572195635;5788.88422231553;5848;1192.1952681939656;1217.6590554644536;1220.798568236249;1277.8466584009793;1299.525673129301;714.3963350968423;830.3855147191035;863;868.1421757439085;882.4176043811182;4569.908601827945;5070.949290507121;5106.744662766847;5126.974365128151;5135.460776618401;4531.705439146445;5056.729655227565;5271.314729085271;5562.527185189223;5595.38450770415;4109.773962432095;4681.95318046822;4723.7401942893475;4950.232713929347;5053.487825960922;2697.824641398303;2944.852757362123;2953.3797902440297;2957.7190166934106;2958.955615665767;5342.252084708144;5756.618411581274;5764.57887687164;5771.384012476513;5797.623154494944;4903.779329930162;5351.698769529062;5588.993981083444;5594.944050559524;5775.133729940459;4427.07422271313;4665.976670116627;5102.489751025017;5696.572757043238;5799.88400231993;4553.772311384484;5066.518680725325;5493.478119578634;5611.242482264842;5622.32532096151;4951.702897826142;5442.428545002717;5495.0601428235705;5882.235309409921;5969.224000879858;4951.480094590016;4980.925286120639;5616.792389636248;6040.818775436744;6200.534959877937;3514.578250609943;3793.748063139102;3969.4045893116263;3995.1809878975323;3999;4982;5669.149627555899;5762.818622182526;5903.609038339479;5947.286325640951;4775.6418268629295;4811.927821082617;5021.974890125673;5621.269234999424;5868.149118377897;5143.3570803649545;5658.292713410824;5788.826335209782;5839.445252700986;5863.648181109147;4799.592034676952;5383.08487557124;5671.404502527175;5722.313322401338;5732.914006289826;4579.404677391918;4846.854594362175;4854.563089321978;5744.482996530165;5797.927383434078;5624.368933009381;5659.971700141473;5669.546436285004;5670.461306175906;5723.599348045597 13:31:15 INFO - PROCESS | 1639 | |5;yui.html;655.0828839624528;708.436792749764;713.5041146403246;730.1785491322264;730.6529398535696;12223.816642750344;12625.36873156343;13844.51544195952;14673.77989330161;14760.6309842254;13044.543440979567;13176.538821141263;13211.40548675312;13243.404046817892;13271.402786874589;633.841539615095;659.3867703036175;787.9133295337508;816.2164322250617;818.5866137600503;728.27536601082;806.3750593290189;810.2464707821723;810.7932477218301;811.37524106438;742.721479445208;858.549261637639;860.9698660546882;863.4301361101674;865.5455885660016;852.0499642898155;858.4806192253691;859.8839156713826;860.8923884514436;862.6678728689437;629.6064959400375;636.4749082007362;637;638.3552611862007;638.4286063972719;11304.773904521864;13391.799123013163;13433.39549720265;13468.25924574148;13501.662458438519;12501.124921255516;12508.874911250861;12663.936680316632;12811.679708007281;13142.802857957042;10874.347539147677;11160.05139563138;11178.608748693838;11178.888211117866;11218.27081239725;3528.0826984983833;3537.7346698997676;3689.3912504436735;3707.2400157967554;3724.845297957642;91848.54075729579;94645;96272;96396;98733.5189972151;10026.69919902404;10050.64822731201;10270.07569318765;10270.6405275815;10406.687799366031;19457.708134377997;19534.218631254877;19567.412977610693;19588.8041119587;19629.21483140687;7264.854702906018;7394.519356241827;7435.776926692208;7480.139783924809;7531.774046778605;3890.299746045739;4176.561461046546;4197.748135111903;4242.342436922282;4243.522232950771;3004.8948286810296;3435.57055368079;3468.0116166892312;3479.9304013920087;3483.89548313551;551.8013515134548;599.4604855629968;602.0487629545308;609.3662590905498;612.9264488261438;501.32571691075447;530.7717681397037;531.776653805395;598.2880372356882;642.3448082955452;7719.922800772033;8695.998011434252;8861.911380886237;9047.366684332033;9053.366264361432;547.8986387518322;562.5864989232911;596.3391953409733;606.4117805728437;648.4164252173082;4316.482022157361;4399.1641588098155;4406.647468202536;4475.216837053567;4539.296409056536;113406;113429.86570134359;116324.41837790757;117647;118421;96834.51582742042;102459;104139.47930260301;105079.94920050702;105931.4703426478;88145.55927220195;88898.55550722204;89547;90672;92969.5351523238;8517.403781735218;8568.828623427622;8648.567571621268;8734.56327183651;8757.956210218908;44520.33219501775;48273.5172648269;53107.73446132745;53109.20336194883;53357.19964200463;76482;76569;77573.61213193898;78113.21886781059;79307;10726.946365268124;10805.783884322427;10837.729056773644;11189.272697274651;11286.548538058385;10940.015398614163;12114.15200935926;12116.21244619097;12284.262944223376;12373.876261237272;8837.823243535222;10146.746331341776;11042.834357484804;11795.053816120244;11954.940225298818;11141.777164456827;11921.046316294502;11938.820917686415;12389.256644601353;12411.937940310241;10980.615678451215;11275.492602832728;11427.485763140512;11552.834539238585;11740.354280514654;9394.95302523483;9467.573959171992;9565.282603804604;9624.181944534794;10329.276950613385;9624.326297159132;9930.453825039693;9956.740231584696;10040.196784257096;10046.447445390573 13:31:15 INFO - PROCESS | 1639 | __end_tp_report 13:31:15 INFO - PROCESS | 1639 | __start_cc_report 13:31:15 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,383 13:31:15 INFO - PROCESS | 1639 | __end_cc_report 13:31:15 INFO - PROCESS | 1639 | __startTimestamp1481923875457__endTimestamp 13:31:15 INFO - PROCESS | 1639 | 13:31:15 INFO - PROCESS | 1639 | ------- Summary: start ------- 13:31:15 INFO - PROCESS | 1639 | Number of tests: 6 13:31:15 INFO - PROCESS | 1639 | 13:31:15 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5695.02 Median:5922.32 stddev:699.03 (11.8%) stddev-sans-first:661.60 13:31:15 INFO - PROCESS | 1639 | Values: 2739.4 3312.5 3327.6 3336.0 3356.8 5623.6 5656.6 5668.5 5732.4 5743.3 5679.1 5725.2 5735.5 5769.5 5774.8 5182.8 5240.3 5247.3 5302.5 5309.3 5294.7 5309.8 5323.2 5344.7 5389.9 4529.3 5227.1 5315.2 5333.2 5334.2 4221.9 5092.7 5167.5 5226.3 5306.5 3995.9 5164.8 5189.2 5236.9 5270.3 5799.2 5804.6 5819.4 5834.7 5854.1 6072.2 6166.4 6224.7 6229.9 6274.7 5092.7 6161.4 6178.7 6186.8 6226.8 5820.2 5832.3 5886.2 5901.6 5905.9 6133.2 6267.3 6273.5 6275.2 6277.0 6059.5 6095.8 6115.5 6117.8 6158.2 6206.2 6230.2 6231.7 6250.5 6277.0 6060.6 6181.0 6188.7 6195.7 6280.6 6110.1 6117.7 6184.2 6232.0 6247.1 4127.6 4603.0 4918.9 4957.8 4986.4 3587.9 4092.8 4251.3 4479.2 4490.5 5663.7 5804.1 5856.7 5872.4 5874.2 5148.2 6080.3 6204.9 6213.6 6250.2 4863.6 5769.0 5777.9 5871.9 5986.6 5408.2 5416.8 5438.7 5445.6 5447.8 6109.6 6263.3 6270.9 6277.9 6291.4 6110.1 6142.7 6143.6 6165.1 6197.7 6230.9 6241.3 6242.1 6248.7 6274.5 6032.7 6165.1 6171.0 6178.3 6184.8 5515.0 5978.3 6124.3 6144.3 6232.7 6148.5 6177.7 6180.7 6196.5 6209.1 6149.0 6156.2 6159.5 6198.3 6205.8 5650.8 5870.4 5870.8 5909.8 5967.1 6037.3 6062.4 6095.7 6105.2 6116.1 5895.2 5922.3 5926.4 5928.6 5937.7 13:31:15 INFO - PROCESS | 1639 | 13:31:15 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:67062.96 Median:39129.31 stddev:145754.48 (372.5%) stddev-sans-first:146077.50 13:31:15 INFO - PROCESS | 1639 | Values: 430.9 489.4 528.0 533.6 553.0 37585.8 39379.0 39430.8 39443.0 39634.8 40414.4 40783.2 40807.6 40866.0 41124.0 9249.9 9428.5 9450.1 9460.2 9478.0 9337.4 9446.6 9453.8 9456.5 9487.4 9420.3 9467.2 9476.9 9495.2 9504.2 9405.4 9445.6 9450.2 9455.9 9500.2 9168.0 9226.0 9230.7 9237.1 9247.9 38410.6 38811.0 38855.4 38878.4 38879.6 3122.5 3141.2 3214.0 3402.0 3661.3 29995.9 30239.5 30303.9 30508.4 30541.8 3171.9 3203.2 3215.8 3221.7 3228.0 356730.2 378418.0 380963.0 381485.0 383663.0 60347.7 60616.7 60669.7 60688.0 60712.7 62429.7 62902.0 63079.0 63103.7 63576.0 14276.1 15872.9 16053.4 16169.8 16183.0 10393.5 10734.7 11968.0 12361.8 12487.9 14850.9 16711.9 16996.3 17095.8 17228.0 3159.5 6065.5 6109.8 6771.6 6985.8 826.6 941.6 978.5 1060.4 1083.7 24400.5 29881.9 30042.0 30074.5 30419.4 995.5 1016.4 1091.3 1131.1 1155.1 14356.4 15028.5 15298.2 15559.3 15573.0 681745.6 689870.0 690595.0 692393.0 695243.5 57624.1 58084.0 58149.0 58279.1 58330.4 1329.1 1346.3 1378.6 1380.1 1381.7 29033.6 29429.1 30251.4 30338.4 30371.8 50722.2 54056.2 55494.7 55562.0 58305.7 518092.0 520169.0 520214.0 523288.0 525888.0 41535.6 41791.6 42126.8 42317.4 42476.8 45943.1 46118.5 47150.0 47192.8 47220.8 37311.3 37969.4 38239.6 38328.6 39645.8 29521.4 33563.8 38747.8 38771.4 39851.0 36648.5 37838.0 38580.0 38658.8 38732.8 30942.4 31378.8 33988.0 34517.0 34626.8 24157.5 25576.0 25800.9 25808.2 26145.6 13:31:15 INFO - PROCESS | 1639 | 13:31:15 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:34387.06 Median:2330.39 stddev:98774.65 (4238.5%) stddev-sans-first:99018.71 13:31:15 INFO - PROCESS | 1639 | Values: 363.8 370.2 375.8 376.8 392.1 15274.5 18853.6 18885.4 18945.7 19050.6 11374.8 14680.1 19096.9 19147.5 19619.2 5247.0 5464.9 5478.3 5484.5 5484.8 3849.6 4825.4 5383.4 6070.1 6100.6 6122.8 6142.8 6143.8 6148.9 6174.1 5714.0 6066.5 6103.1 6128.2 6131.5 5809.1 5863.3 5885.1 5911.7 5953.7 13042.7 13140.8 13202.0 13273.8 21076.7 2285.2 2375.6 2405.9 2466.3 2894.7 9524.4 9972.3 10011.8 10140.4 10176.5 1854.7 1884.2 1896.7 1908.8 1914.0 93073.2 107447.0 109807.0 116994.8 121190.4 11156.6 11277.9 11372.1 11810.4 11949.4 14167.4 14220.1 14400.8 14445.5 14456.6 8463.4 8559.6 8591.0 8628.8 8696.4 8188.9 8365.8 8401.7 8417.7 8557.1 4517.5 4546.8 4551.8 4578.0 4650.5 1573.9 1683.2 1689.6 1905.2 1941.9 866.8 874.7 919.7 934.6 971.8 20579.8 20817.7 21214.7 21250.3 21341.6 923.7 925.9 936.2 992.0 1003.5 10463.1 11171.7 11255.4 11320.9 11323.2 581047.0 581106.0 588580.0 593794.0 599457.0 138661.3 144010.1 144860.0 146691.0 148378.5 965.0 1004.4 1016.4 1019.4 1020.8 18436.1 19366.0 19725.3 19729.4 19812.5 35659.6 42209.7 42628.6 52650.7 55270.2 79236.0 79503.2 81868.6 82888.6 83142.6 7071.8 8028.0 8147.2 8219.5 8275.7 10858.0 11648.5 13302.2 13381.9 13523.9 10972.5 11187.6 11219.0 11445.9 11510.3 5122.8 10966.8 11056.1 11421.3 11577.5 9507.6 10269.8 10864.6 11403.7 11628.4 6324.3 7402.7 7418.1 7533.2 7612.5 10826.2 11320.7 11320.8 11555.5 11700.5 13:31:15 INFO - PROCESS | 1639 | 13:31:15 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5397.65 Median:5087.02 stddev:2992.07 (58.8%) stddev-sans-first:2985.67 13:31:15 INFO - PROCESS | 1639 | Values: 1438.0 1465.9 1466.0 1627.7 1770.9 7347.4 7775.3 8098.6 8575.3 8577.7 7809.4 8307.0 8342.6 8415.2 8460.7 2292.3 2332.2 2332.9 2335.4 2336.0 2318.0 2350.0 2374.1 2375.5 2380.9 2314.0 2322.9 2341.6 2341.9 2342.2 1959.6 1963.9 1977.2 1978.5 1983.9 2313.4 2339.2 2340.0 2340.6 2343.2 8356.4 8413.0 8437.5 8445.7 8493.5 4369.8 5240.2 5246.0 5256.6 5262.6 3254.3 3368.8 3580.2 3581.8 3596.4 1038.1 1050.8 1076.4 1085.5 1106.3 10929.7 10997.8 11033.7 11078.6 11080.4 3918.3 4207.9 4613.5 4687.6 4701.3 7734.8 8175.6 9489.7 9556.5 9562.2 4415.4 5201.1 5208.4 5220.2 5230.8 4195.8 4412.7 4441.3 4747.5 4776.6 3646.9 3704.0 3805.9 3816.8 3825.1 1254.5 1281.8 1291.3 1301.8 1308.3 845.3 851.8 857.8 878.7 884.6 5368.4 5411.6 5442.7 5500.0 5500.4 844.9 856.1 877.6 880.2 910.8 3227.9 3290.6 3550.9 3626.5 3743.8 9838.2 10093.4 10162.4 10168.0 10178.8 10042.6 10050.7 10052.6 10123.7 10158.0 7346.6 7704.9 7837.9 7878.8 7878.8 5413.2 5414.2 5657.1 5674.6 5743.7 9201.6 9297.9 9345.0 9408.5 9417.8 9329.0 9359.6 9415.6 9496.6 9504.1 6022.7 6502.7 6812.3 6893.7 7325.6 7879.7 7978.8 8054.8 8058.4 8083.3 6973.1 7166.6 7179.9 7183.6 7220.1 6719.4 6965.3 7035.6 7070.2 7146.5 6654.6 6658.7 6751.4 6755.6 6756.3 4740.5 4755.5 4793.4 4800.9 4811.7 4665.4 4756.0 4780.0 4886.5 4972.9 13:31:15 INFO - PROCESS | 1639 | 13:31:15 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4492.05 Median:4950.86 stddev:1400.44 (28.3%) stddev-sans-first:1368.25 13:31:15 INFO - PROCESS | 1639 | Values: 281.9 290.2 301.1 311.3 314.9 4399.8 4618.7 5136.4 5473.8 5475.7 4470.0 4877.4 5381.0 5406.5 5453.3 4107.7 4115.7 4150.3 4172.5 4183.6 3190.8 4047.6 4136.9 4165.0 4176.0 3600.0 3955.7 4080.2 4103.3 4152.0 3719.9 3720.7 4084.5 4106.4 4129.3 3137.5 3326.3 3534.1 3544.4 3556.3 4092.9 5004.4 5189.3 5305.9 5311.7 3851.7 4603.6 4648.5 4657.8 4672.3 5246.7 5462.2 5658.6 5737.8 5778.7 3022.5 3549.6 3643.7 3692.4 3850.2 5988.8 5998.5 6013.8 6036.0 6036.5 3314.5 3884.0 3892.3 3896.9 3940.8 3597.2 3735.2 3753.5 3914.3 3963.1 4609.6 4650.2 4656.7 4668.3 4688.7 5723.5 5763.6 5783.5 5788.9 5848.0 1192.2 1217.7 1220.8 1277.8 1299.5 714.4 830.4 863.0 868.1 882.4 4569.9 5070.9 5106.7 5127.0 5135.5 4531.7 5056.7 5271.3 5562.5 5595.4 4109.8 4682.0 4723.7 4950.2 5053.5 2697.8 2944.9 2953.4 2957.7 2959.0 5342.3 5756.6 5764.6 5771.4 5797.6 4903.8 5351.7 5589.0 5594.9 5775.1 4427.1 4666.0 5102.5 5696.6 5799.9 4553.8 5066.5 5493.5 5611.2 5622.3 4951.7 5442.4 5495.1 5882.2 5969.2 4951.5 4980.9 5616.8 6040.8 6200.5 3514.6 3793.7 3969.4 3995.2 3999.0 4982.0 5669.1 5762.8 5903.6 5947.3 4775.6 4811.9 5022.0 5621.3 5868.1 5143.4 5658.3 5788.8 5839.4 5863.6 4799.6 5383.1 5671.4 5722.3 5732.9 4579.4 4846.9 4854.6 5744.5 5797.9 5624.4 5660.0 5669.5 5670.5 5723.6 13:31:15 INFO - PROCESS | 1639 | 13:31:15 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20810.71 Median:4507.26 stddev:32012.15 (710.2%) stddev-sans-first:32066.18 13:31:15 INFO - PROCESS | 1639 | Values: 655.1 708.4 713.5 730.2 730.7 12223.8 12625.4 13844.5 14673.8 14760.6 13044.5 13176.5 13211.4 13243.4 13271.4 633.8 659.4 787.9 816.2 818.6 728.3 806.4 810.2 810.8 811.4 742.7 858.5 861.0 863.4 865.5 852.0 858.5 859.9 860.9 862.7 629.6 636.5 637.0 638.4 638.4 11304.8 13391.8 13433.4 13468.3 13501.7 12501.1 12508.9 12663.9 12811.7 13142.8 10874.3 11160.1 11178.6 11178.9 11218.3 3528.1 3537.7 3689.4 3707.2 3724.8 91848.5 94645.0 96272.0 96396.0 98733.5 10026.7 10050.6 10270.1 10270.6 10406.7 19457.7 19534.2 19567.4 19588.8 19629.2 7264.9 7394.5 7435.8 7480.1 7531.8 3890.3 4176.6 4197.7 4242.3 4243.5 3004.9 3435.6 3468.0 3479.9 3483.9 551.8 599.5 602.0 609.4 612.9 501.3 530.8 531.8 598.3 642.3 7719.9 8696.0 8861.9 9047.4 9053.4 547.9 562.6 596.3 606.4 648.4 4316.5 4399.2 4406.6 4475.2 4539.3 113406.0 113429.9 116324.4 117647.0 118421.0 96834.5 102459.0 104139.5 105079.9 105931.5 88145.6 88898.6 89547.0 90672.0 92969.5 8517.4 8568.8 8648.6 8734.6 8758.0 44520.3 48273.5 53107.7 53109.2 53357.2 76482.0 76569.0 77573.6 78113.2 79307.0 10726.9 10805.8 10837.7 11189.3 11286.5 10940.0 12114.2 12116.2 12284.3 12373.9 8837.8 10146.7 11042.8 11795.1 11954.9 11141.8 11921.0 11938.8 12389.3 12411.9 10980.6 11275.5 11427.5 11552.8 11740.4 9395.0 9467.6 9565.3 9624.2 10329.3 9624.3 9930.5 9956.7 10040.2 10046.4 13:31:15 INFO - PROCESS | 1639 | -------- Summary: end -------- 13:31:15 INFO - PROCESS | 1639 | 13:31:16 INFO - TEST-INFO | 1639: exit 0 13:31:16 INFO - TEST-OK | dromaeo_css | took 1116724ms 13:31:16 INFO - TEST-START | kraken 13:31:16 INFO - operating with platform_type : mac_ 13:31:16 INFO - Initialising browser for kraken test... 13:31:16 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmphUUfcf/profile http://localhost:49241/getInfo.html) 13:31:18 INFO - PROCESS | 1649 | 2016-12-16 13:31:18.967 plugin-container[1650:10504] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 13:31:18 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:31:18 INFO - PROCESS | 1649 | 2016-12-16 13:31:18.969 plugin-container[1650:10504] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 13:31:18 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:31:18 INFO - PROCESS | 1649 | 2016-12-16 13:31:18.969 plugin-container[1650:10504] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:31:19 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 13:31:19 INFO - PROCESS | 1649 | colorDepth:24 13:31:19 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 13:31:19 INFO - PROCESS | 1649 | __metrics 13:31:19 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 13:31:20 INFO - TEST-INFO | 1649: exit 0 13:31:20 INFO - Browser initialized. 13:31:20 INFO - Running cycle 1/1 for kraken test... 13:31:20 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmphUUfcf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:31:22 INFO - PROCESS | 1651 | 2016-12-16 13:31:22.706 plugin-container[1652:10629] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8333, name = 'com.apple.tsm.portname' 13:31:22 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:31:22 INFO - PROCESS | 1651 | 2016-12-16 13:31:22.708 plugin-container[1652:10629] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 13:31:22 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:31:22 INFO - PROCESS | 1651 | 2016-12-16 13:31:22.708 plugin-container[1652:10629] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:31:32 INFO - PROCESS | 1651 | RSS: Main: 229498880 13:31:32 INFO - PROCESS | 1651 | 13:31:35 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 13:31:36 INFO - PROCESS | 1651 | RSS: Main: 241549312 13:31:36 INFO - PROCESS | 1651 | 13:31:38 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 13:31:39 INFO - PROCESS | 1651 | RSS: Main: 249196544 13:31:39 INFO - PROCESS | 1651 | 13:31:42 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 13:31:43 INFO - PROCESS | 1651 | RSS: Main: 252989440 13:31:43 INFO - PROCESS | 1651 | 13:31:45 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 13:31:45 INFO - PROCESS | 1651 | RSS: Main: 253227008 13:31:45 INFO - PROCESS | 1651 | 13:31:47 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:31:48 INFO - PROCESS | 1651 | RSS: Main: 253181952 13:31:48 INFO - PROCESS | 1651 | 13:31:51 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 13:31:52 INFO - PROCESS | 1651 | RSS: Main: 258572288 13:31:52 INFO - PROCESS | 1651 | 13:31:54 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 13:31:55 INFO - PROCESS | 1651 | RSS: Main: 258523136 13:31:55 INFO - PROCESS | 1651 | 13:31:58 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 13:31:59 INFO - PROCESS | 1651 | RSS: Main: 258060288 13:31:59 INFO - PROCESS | 1651 | 13:32:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:32:01 INFO - PROCESS | 1651 | RSS: Main: 256909312 13:32:01 INFO - PROCESS | 1651 | 13:32:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:32:04 INFO - PROCESS | 1651 | RSS: Main: 257150976 13:32:04 INFO - PROCESS | 1651 | 13:32:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:32:07 INFO - PROCESS | 1651 | RSS: Main: 257073152 13:32:07 INFO - PROCESS | 1651 | 13:32:10 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:32:11 INFO - PROCESS | 1651 | RSS: Main: 257437696 13:32:11 INFO - PROCESS | 1651 | 13:32:14 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:32:14 INFO - PROCESS | 1651 | RSS: Main: 257810432 13:32:14 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 13:32:16 INFO - PROCESS | 1651 | RSS: Main: 259440640 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | __start_tp_report 13:32:16 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 13:32:16 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 13:32:16 INFO - PROCESS | 1651 | |i|pagename|runs| 13:32:16 INFO - PROCESS | 1651 | |0;ai-astar;169;173;122;119;117;118;119;119;118;117 13:32:16 INFO - PROCESS | 1651 | |1;audio-beat-detection;143;112;113;173;117;122;139;128;141;113 13:32:16 INFO - PROCESS | 1651 | |2;audio-dft;199;198;200;190;198;193;195;198;203;195 13:32:16 INFO - PROCESS | 1651 | |3;audio-fft;82;58;61;88;62;62;77;83;69;74 13:32:16 INFO - PROCESS | 1651 | |4;audio-oscillator;80;58;101;104;99;103;122;72;71;88 13:32:16 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;133;121;89;75;100;84;136;130;88;88 13:32:16 INFO - PROCESS | 1651 | |6;imaging-darkroom;98;99;97;99;100;98;112;100;97;99 13:32:16 INFO - PROCESS | 1651 | |7;imaging-desaturate;119;90;94;134;72;137;143;103;96;117 13:32:16 INFO - PROCESS | 1651 | |8;json-parse-financial;56;58;56;60;60;61;59;59;60;89 13:32:16 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;61;66;80;112;107;71;78;98;106;103 13:32:16 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;84;84;89;79;96;101;100;102;84;85 13:32:16 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;139;130;152;152;168;153;147;159;186;169 13:32:16 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;168;170;172;158;212;231;170;167;167;167 13:32:16 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;68;68;67;67;69;72;85;85;83;84 13:32:16 INFO - PROCESS | 1651 | __end_tp_report 13:32:16 INFO - PROCESS | 1651 | __start_cc_report 13:32:16 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,979 13:32:16 INFO - PROCESS | 1651 | __end_cc_report 13:32:16 INFO - PROCESS | 1651 | __startTimestamp1481923936743__endTimestamp 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | ------- Summary: start ------- 13:32:16 INFO - PROCESS | 1651 | Number of tests: 14 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:129.10 Median:119.00 stddev:22.15 (18.6%) stddev-sans-first:18.19 13:32:16 INFO - PROCESS | 1651 | Values: 169.0 173.0 122.0 119.0 117.0 118.0 119.0 119.0 118.0 117.0 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:130.10 Median:133.50 stddev:19.32 (14.5%) stddev-sans-first:19.92 13:32:16 INFO - PROCESS | 1651 | Values: 143.0 112.0 113.0 173.0 117.0 122.0 139.0 128.0 141.0 113.0 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:196.90 Median:198.00 stddev:3.73 (1.9%) stddev-sans-first:3.87 13:32:16 INFO - PROCESS | 1651 | Values: 199.0 198.0 200.0 190.0 198.0 193.0 195.0 198.0 203.0 195.0 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:71.60 Median:75.50 stddev:10.70 (14.2%) stddev-sans-first:10.67 13:32:16 INFO - PROCESS | 1651 | Values: 82.0 58.0 61.0 88.0 62.0 62.0 77.0 83.0 69.0 74.0 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:71.50 stddev:19.45 (27.2%) stddev-sans-first:20.30 13:32:16 INFO - PROCESS | 1651 | Values: 80.0 58.0 101.0 104.0 99.0 103.0 122.0 72.0 71.0 88.0 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:104.40 Median:79.50 stddev:23.15 (29.1%) stddev-sans-first:22.12 13:32:16 INFO - PROCESS | 1651 | Values: 133.0 121.0 89.0 75.0 100.0 84.0 136.0 130.0 88.0 88.0 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:99.90 Median:98.00 stddev:4.38 (4.5%) stddev-sans-first:4.59 13:32:16 INFO - PROCESS | 1651 | Values: 98.0 99.0 97.0 99.0 100.0 98.0 112.0 100.0 97.0 99.0 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:110.50 Median:107.50 stddev:23.25 (21.6%) stddev-sans-first:24.46 13:32:16 INFO - PROCESS | 1651 | Values: 119.0 90.0 94.0 134.0 72.0 137.0 143.0 103.0 96.0 117.0 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:61.80 Median:60.00 stddev:9.70 (16.2%) stddev-sans-first:10.06 13:32:16 INFO - PROCESS | 1651 | Values: 56.0 58.0 56.0 60.0 60.0 61.0 59.0 59.0 60.0 89.0 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:88.20 Median:68.50 stddev:19.01 (27.7%) stddev-sans-first:17.43 13:32:16 INFO - PROCESS | 1651 | Values: 61.0 66.0 80.0 112.0 107.0 71.0 78.0 98.0 106.0 103.0 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:90.40 Median:84.00 stddev:8.53 (10.2%) stddev-sans-first:8.72 13:32:16 INFO - PROCESS | 1651 | Values: 84.0 84.0 89.0 79.0 96.0 101.0 100.0 102.0 84.0 85.0 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:155.50 Median:156.00 stddev:16.01 (10.3%) stddev-sans-first:15.83 13:32:16 INFO - PROCESS | 1651 | Values: 139.0 130.0 152.0 152.0 168.0 153.0 147.0 159.0 186.0 169.0 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.20 Median:170.00 stddev:23.55 (13.9%) stddev-sans-first:24.69 13:32:16 INFO - PROCESS | 1651 | Values: 168.0 170.0 172.0 158.0 212.0 231.0 170.0 167.0 167.0 167.0 13:32:16 INFO - PROCESS | 1651 | 13:32:16 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.80 Median:77.50 stddev:8.27 (10.7%) stddev-sans-first:8.40 13:32:16 INFO - PROCESS | 1651 | Values: 68.0 68.0 67.0 67.0 69.0 72.0 85.0 85.0 83.0 84.0 13:32:16 INFO - PROCESS | 1651 | -------- Summary: end -------- 13:32:16 INFO - PROCESS | 1651 | 13:32:17 INFO - TEST-INFO | 1651: exit 0 13:32:17 INFO - TEST-OK | kraken | took 61858ms 13:32:17 INFO - SUITE-END | took 1178s 13:32:18 INFO - Completed test suite (00:19:39) 13:32:18 INFO - javascript benchmark 13:32:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7084.666136146619, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2739.397332586831, 3312.4700047992296, 3327.550780644613, 3335.966640333596, 3356.7650264481495, 5623.550115990721, 5656.604037717357, 5668.489835914762, 5732.426757324265, 5743.282089738783, 5679.148127780835, 5725.169850371693, 5735.512481439083, 5769.480746732793, 5774.797882074117, 5182.818601348944, 5240.266362709223, 5247.344081989751, 5302.469753024705, 5309.30978972733, 5294.735263236823, 5309.7610607522565, 5323.174907889264, 5344.652597581165, 5389.865253368677, 4529.275315949449, 5227.059129356714, 5315.176147697113, 5333.226682131108, 5334.1732031535175, 4221.91556168878, 5092.694438333675, 5167.48325167484, 5226.320578324831, 5306.4958828911385, 3995.900102497432, 5164.819231326923, 5189.221616757479, 5236.947630523684, 5270.262163297141, 5799.159121927297, 5804.564657650693, 5819.388964158787, 5834.679092649945, 5854.092615644582, 6072.240969878765, 6166.352532984017, 6224.688765561749, 6229.87540249197, 6274.717637706314, 5092.719900405476, 6161.445469907685, 6178.721957511878, 6186.783462578833, 6226.813195604139, 5820.214271073393, 5832.300123985106, 5886.175935369051, 5901.557383196277, 5905.852353691193, 6133.212252453595, 6267.2792628847355, 6273.466755325757, 6275.152854364603, 6276.968615156895, 6059.515238780887, 6095.817125486243, 6115.51416190291, 6117.827404800917, 6158.1686472326555, 6206.224221972254, 6230.158928544589, 6231.719572619152, 6250.531210159256, 6277.0, 6060.606060606046, 6181.0, 6188.690565471708, 6195.721192546346, 6280.591761535486, 6110.103144028831, 6117.736059405486, 6184.196054512884, 6232.034034724625, 6247.125402443661, 4127.607877251657, 4602.987342784582, 4918.877028074256, 4957.801687932514, 4986.351774269394, 3587.9056887648994, 4092.7544347339253, 4251.341042138473, 4479.238529449936, 4490.483594386687, 5663.745131469093, 5804.1293805929445, 5856.707164641751, 5872.383399743051, 5874.1776151338845, 5148.227765835155, 6080.331163572004, 6204.875902482016, 6213.596116252429, 6250.1874756281395, 4863.586595139453, 5769.0, 5777.90219858234, 5871.91369596626, 5986.610870293504, 5408.161734931092, 5416.756245968941, 5438.700871452108, 5445.618806683573, 5447.836564903059, 6109.586862300561, 6263.311035785969, 6270.9372906270355, 6277.874442511215, 6291.370862913672, 6110.0727721002, 6142.744345496893, 6143.634700640375, 6165.136880836776, 6197.690115494297, 6230.937690623035, 6241.251049874044, 6242.063690446288, 6248.68756562161, 6274.529410294337, 6032.6983650818165, 6165.136880836776, 6170.969145154246, 6178.289496707936, 6184.845378865564, 5514.972425137848, 5978.312494063064, 6124.319907527303, 6144.310314663325, 6232.691134861549, 6148.47737942262, 6177.722002509988, 6180.6600636965395, 6196.47329976957, 6209.0686397039, 6148.9692551536955, 6156.199694039746, 6159.476444502267, 6198.318184999556, 6205.751769929152, 5650.773969041356, 5870.412958704095, 5870.823875283749, 5909.793157239476, 5967.134765459071, 6037.305709843425, 6062.393760624079, 6095.695215239309, 6105.236845394325, 6116.113163591343, 5895.174675545341, 5922.318933322723, 5926.407359264039, 5928.614640048383, 5937.673427961504], "value": 5647.638259496998, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.9310510318349, 489.4298142663797, 528.0442399257345, 533.6157966264288, 553.007351803513, 37585.81207093964, 39379.0, 39430.8028459858, 39443.013924651896, 39634.8018259909, 40414.39378409326, 40783.18433631326, 40807.59192408075, 40866.0, 41123.9719007025, 9249.907500924972, 9428.481433521154, 9450.14948654621, 9460.24318054554, 9478.0, 9337.439753614804, 9446.622135114587, 9453.81092378152, 9456.527173641325, 9487.383320084207, 9420.340576159637, 9467.19528840046, 9476.905230947707, 9495.240380769557, 9504.192143667797, 9405.388649737779, 9445.574949127305, 9450.243980481546, 9455.858162127573, 9500.192483638899, 9168.03735607765, 9225.953870230673, 9230.723078307661, 9237.07629237071, 9247.907520924839, 38410.615893840986, 38811.0, 38855.41716874249, 38878.41682374767, 38879.611203887885, 3122.4535706251313, 3141.2146963259183, 3213.951790723141, 3401.9794061781367, 3661.340958627446, 29995.850020749975, 30239.546406803915, 30303.93936212244, 30508.389832203455, 30541.847290763402, 3171.904842854718, 3203.167176534118, 3215.758818088653, 3221.677832216783, 3227.950915952325, 356730.2163489192, 378418.0, 380963.0, 381485.0, 383663.0, 60347.69826150841, 60616.69691651602, 60669.69665151734, 60688.0, 60712.69643651754, 62429.68785156136, 62902.0, 63079.0, 63103.68448157822, 63576.0, 14276.14343139415, 15872.92063539675, 16053.438129665406, 16169.757453638205, 16183.0, 10393.48032598367, 10734.73163170927, 11967.982721468772, 12361.814572781415, 12487.937560312323, 14850.925745371203, 16711.913725607796, 16996.32014719422, 17095.829041709425, 17227.96632202072, 3159.4786860168274, 6065.5450841187085, 6109.755609775648, 6771.593704377753, 6985.825354366079, 826.5536610230529, 941.6233506597279, 978.4569563891966, 1060.3637817309552, 1083.6809228251548, 24400.511989760107, 29881.850590746908, 30042.0, 30074.54888176679, 30419.391612168074, 995.5470261031213, 1016.4358780876537, 1091.317926296065, 1131.145984781501, 1155.1402242389745, 14356.353964071643, 15028.474003409829, 15298.235088245543, 15559.29983150761, 15572.987755795837, 681745.5912720504, 689870.0, 690595.0, 692393.0, 695243.5237823778, 57624.13563796546, 58084.0, 58149.0, 58279.125813112834, 58330.4166958325, 1329.1360615599938, 1346.3268365817091, 1378.5657517881834, 1380.1236215003546, 1381.6614929342375, 29033.564496532148, 29429.11712648624, 30251.394972100876, 30338.393232135677, 30371.848140759153, 50722.2391664118, 54056.18915716189, 55494.72252638711, 55562.0, 58305.70847145737, 518092.0, 520169.0, 520214.0, 523288.0, 525888.0, 41535.58464415317, 41791.58208417999, 42126.78936605297, 42317.36523952082, 42476.78761606172, 45943.08113837771, 46118.53881461143, 47150.0, 47192.7640361796, 47220.7638961803, 37311.2537749238, 37969.43045854259, 38239.61760382472, 38328.6167138325, 39645.80177099096, 29521.409571808872, 33563.83218083894, 38747.806260968515, 38771.41842872416, 39851.0, 36648.45027324645, 37838.0, 38580.0, 38658.840234793, 38732.80633596814, 30942.38115237728, 31378.843105784326, 33988.0, 34516.96449106531, 34626.82686586652, 24157.51684966326, 25576.0, 25800.870995644902, 25808.18216137846, 26145.607815882395], "value": 19634.9319787733, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.77809536182923, 370.1855916982638, 375.7895578476053, 376.7682875031856, 392.05905826017556, 15274.465393711222, 18853.622927541473, 18885.43343699691, 18945.71581426278, 19050.61898762024, 11374.829377559323, 14680.11919284841, 19096.904515477403, 19147.521311967208, 19619.215231390728, 5246.973765131169, 5464.907018596276, 5478.287822583058, 5484.533814625762, 5484.808031718891, 3849.615038496156, 4825.372701548811, 5383.353997520303, 6070.089486577004, 6100.572959892809, 6122.816315510519, 6142.78500252489, 6143.754249830002, 6148.87702245957, 6174.10475481055, 5714.0, 6066.514678825705, 6103.145559621678, 6128.203333566652, 6131.47882429994, 5809.099589563624, 5863.296404431496, 5885.058390657474, 5911.674857882857, 5953.672548009858, 13042.673933151653, 13140.802887956688, 13202.0, 13273.800892986614, 21076.683849742112, 2285.211601997308, 2375.572396968545, 2405.9157929472385, 2466.3217615155795, 2894.72500112489, 9524.428534287965, 9972.301938864282, 10011.849822252672, 10140.442275674908, 10176.491175441199, 1854.7217917312375, 1884.180381534037, 1896.7344571759966, 1908.809119088094, 1913.9855876385539, 93073.207803766, 107447.0, 109807.0, 116994.83005170009, 121190.39404803008, 11156.553737850558, 11277.887221127849, 11372.090232781358, 11810.350430726221, 11949.402529873472, 14167.433302667983, 14220.146791192561, 14400.783988240184, 14445.49440769568, 14456.638584035483, 8463.407561470638, 8559.57202139903, 8591.0, 8628.78428039304, 8696.391252612382, 8188.877166842502, 8365.8326833463, 8401.663933442594, 8417.70538031166, 8557.058723540405, 4517.457905051415, 4546.795394207269, 4551.840685575989, 4577.977110114428, 4650.51169627191, 1573.889827712049, 1683.2004797720986, 1689.6367281034636, 1905.180772267938, 1941.883486990785, 866.757307953774, 874.6851133591902, 919.72868003939, 934.5794392523392, 971.7959228562081, 20579.794202057787, 20817.68773468399, 21214.681779773317, 21250.2562410318, 21341.573168536543, 923.7228831350568, 925.9259259259243, 936.2369668719972, 991.9801603968025, 1003.4731765823001, 10463.110635595907, 11171.66485005451, 11255.380954047607, 11320.886791132149, 11323.150763692756, 581047.0, 581106.0, 588580.0, 593794.0, 599457.0, 138661.3066934659, 144010.11979760346, 144860.0, 146691.0, 148378.51621483863, 964.9614015439445, 1004.4124187350482, 1016.4409574734015, 1019.4342140112309, 1020.7754294055296, 18436.078196089875, 19366.031698415303, 19725.309614163434, 19729.40811775649, 19812.50468738293, 35659.57361705503, 42209.73370798881, 42628.5737142637, 52650.736746316026, 55270.170947435014, 79236.0, 79503.20496794958, 81868.59065704871, 82888.58555707183, 83142.58428707818, 7071.752488662872, 8028.036635603765, 8147.226013528705, 8219.54792486419, 8275.710350137717, 10858.0, 11648.475818588015, 13302.201867887958, 13381.933090334485, 13523.932380338036, 10972.451377430936, 11187.608433704781, 11219.046381057702, 11445.935931803919, 11510.30938143714, 5122.820701275437, 10966.835497467704, 11056.115510758958, 11421.31472111676, 11577.536898523964, 9507.619695212114, 10269.84595231087, 10864.565417383532, 11403.657890263305, 11628.418579070843, 6324.30432652417, 7402.6668799903055, 7418.072740907387, 7533.2466753324225, 7612.467127301035, 10826.242163048511, 11320.660380188607, 11320.773584528428, 11555.524539663153, 11700.531978720755], "value": 9269.51926600179, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.978430323545, 1465.868071873531, 1466.0250933129469, 1627.7151498487763, 1770.8760386772924, 7347.448941329395, 7775.30022297993, 8098.635561399744, 8575.31397488201, 8577.742667719971, 7809.414293927964, 8307.00315962084, 8342.582870856448, 8415.24262816348, 8460.703875364348, 2292.346681195855, 2332.24202134307, 2332.941676458085, 2335.369450248432, 2335.9532809343805, 2317.9652305215436, 2350.048230466664, 2374.050379848057, 2375.5367703297866, 2380.9166679166233, 2313.9884300578474, 2322.907083716649, 2341.590221711202, 2341.91803286885, 2342.227065068532, 1959.6472634925708, 1963.9312624058089, 1977.2091163534558, 1978.4658142301641, 1983.9008049597535, 2313.4332088638225, 2339.204670412051, 2340.028888011473, 2340.648902664597, 2343.2267351773876, 8356.373272004625, 8413.0, 8437.451565648213, 8445.70440034602, 8493.490390576524, 4369.80335884886, 5240.187770895517, 5245.973770131163, 5256.605754568384, 5262.552683021948, 3254.3491301739746, 3368.8484018219237, 3580.1944562473495, 3581.83881725323, 3596.4425514045365, 1038.1072277841051, 1050.753073027838, 1076.4025965589094, 1085.4626959654943, 1106.3140852671315, 10929.672109836718, 10997.835032474519, 11033.668989930235, 11078.556857725682, 11080.445977701163, 3918.3338832398563, 4207.894802629898, 4613.469451013109, 4687.5781179693995, 4701.294805779092, 7734.806629834186, 8175.5503447309775, 9489.667861624948, 9556.474393908402, 9562.187214086742, 4415.381846541487, 5201.115810312256, 5208.374995000549, 5220.190870415092, 5230.790768369223, 4195.832166713359, 4412.7352358857925, 4441.311596702516, 4747.525247475225, 4776.617870570346, 3646.9452958205648, 3704.0, 3805.8858234253016, 3816.809159542012, 3825.1202223488754, 1254.523281153156, 1281.8461784585909, 1291.2704322058007, 1301.7786976214065, 1308.3131356037966, 845.3195177881864, 851.7827953871847, 857.8327226190958, 878.6704985630389, 884.5533005832013, 5368.355797304349, 5411.648242864201, 5442.673439593558, 5500.009998200362, 5500.3949565547755, 844.8732690096534, 856.135303343615, 877.5743764274322, 880.1858281089966, 910.7905181808221, 3227.9193020174685, 3290.6380298167182, 3550.8934731958084, 3626.492291079251, 3743.7566558174176, 9838.212942964547, 10093.394396336244, 10162.39025658463, 10168.0, 10178.796424071477, 10042.648507302209, 10050.748731281776, 10052.59789608422, 10123.7469063274, 10158.0, 7346.63266836656, 7704.8844267336035, 7837.882431763642, 7878.842423151505, 7878.842423151505, 5413.160960051198, 5414.160805075219, 5657.12314591239, 5674.602777805679, 5743.741531631003, 9201.585928633094, 9297.86053209216, 9345.018773029005, 9408.482533460452, 9417.759460472087, 9329.0, 9359.625614975324, 9415.623375065195, 9496.572654230715, 9504.144626983605, 6022.698865056817, 6502.674866256573, 6812.284710105566, 6893.655317234218, 7325.6337183141695, 7879.724209652634, 7978.760637180894, 8054.798630034296, 8058.3553315733425, 8083.312918402002, 6973.058637083929, 7166.605836679033, 7179.892301615376, 7183.568985860865, 7220.133583969957, 6719.428848547928, 6965.268646792217, 7035.648217589202, 7070.15158181001, 7146.499745017799, 6654.633995130314, 6658.700358483783, 6751.358620931003, 6755.594664320157, 6756.256811750802, 4740.52594740537, 4755.500672429345, 4793.376861008047, 4800.927986080282, 4811.735354555393, 4665.370175026464, 4755.976220118877, 4779.976100119617, 4886.486918873609, 4972.930819873744], "value": 4362.854654057253, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [281.8759745711887, 290.21496851017974, 301.11922626318017, 311.3120004789415, 314.878771672906, 4399.802008909599, 4618.6836751525825, 5136.38363396392, 5473.753681084351, 5475.67145971242, 4469.977650111745, 4877.365942427479, 5381.040427764724, 5406.486383793543, 5453.318335208099, 4107.67138628909, 4115.71190016699, 4150.294449943509, 4172.5201601815725, 4183.644390226819, 3190.8245046522434, 4047.615476529725, 4136.875893723193, 4165.0, 4175.958240417603, 3599.982000089996, 3955.7230993830444, 4080.1843179785005, 4103.317639767696, 4151.979240103795, 3719.8654410404815, 3720.6837418819296, 4084.530279017921, 4106.40457133714, 4129.327622312968, 3137.4823154179535, 3326.334733053399, 3534.0771743364844, 3544.379733546619, 3556.3065202285566, 4092.897677558085, 5004.399472063339, 5189.325387699575, 5305.920411193835, 5311.7344132793205, 3851.6504931991462, 4603.608693261077, 4648.465426475965, 4657.790399432034, 4672.322513235562, 5246.685198888041, 5462.153366228241, 5658.632188907708, 5737.827865164052, 5778.653280803165, 3022.531507616323, 3549.6272891346293, 3643.67206951373, 3692.4461330800327, 3850.1519270750196, 5988.790392336291, 5998.460138587505, 6013.849653758647, 6036.008117321274, 6036.456718895277, 3314.4696848504122, 3883.9611603884164, 3892.260470510594, 3896.8636097736444, 3940.802959851996, 3597.1546686528645, 3735.1884336096973, 3753.5361209128464, 3914.316843757155, 3963.0884896473394, 4609.585137337655, 4650.162970665313, 4656.697314674535, 4668.346431499524, 4688.742119183409, 5723.456271654186, 5763.567732420001, 5783.450572195635, 5788.88422231553, 5848.0, 1192.1952681939656, 1217.6590554644536, 1220.798568236249, 1277.8466584009793, 1299.525673129301, 714.3963350968423, 830.3855147191035, 863.0, 868.1421757439085, 882.4176043811182, 4569.908601827945, 5070.949290507121, 5106.744662766847, 5126.974365128151, 5135.460776618401, 4531.705439146445, 5056.729655227565, 5271.314729085271, 5562.527185189223, 5595.38450770415, 4109.773962432095, 4681.95318046822, 4723.7401942893475, 4950.232713929347, 5053.487825960922, 2697.824641398303, 2944.852757362123, 2953.3797902440297, 2957.7190166934106, 2958.955615665767, 5342.252084708144, 5756.618411581274, 5764.57887687164, 5771.384012476513, 5797.623154494944, 4903.779329930162, 5351.698769529062, 5588.993981083444, 5594.944050559524, 5775.133729940459, 4427.07422271313, 4665.976670116627, 5102.489751025017, 5696.572757043238, 5799.88400231993, 4553.772311384484, 5066.518680725325, 5493.478119578634, 5611.242482264842, 5622.32532096151, 4951.702897826142, 5442.428545002717, 5495.0601428235705, 5882.235309409921, 5969.224000879858, 4951.480094590016, 4980.925286120639, 5616.792389636248, 6040.818775436744, 6200.534959877937, 3514.578250609943, 3793.748063139102, 3969.4045893116263, 3995.1809878975323, 3999.0, 4982.0, 5669.149627555899, 5762.818622182526, 5903.609038339479, 5947.286325640951, 4775.6418268629295, 4811.927821082617, 5021.974890125673, 5621.269234999424, 5868.149118377897, 5143.3570803649545, 5658.292713410824, 5788.826335209782, 5839.445252700986, 5863.648181109147, 4799.592034676952, 5383.08487557124, 5671.404502527175, 5722.313322401338, 5732.914006289826, 4579.404677391918, 4846.854594362175, 4854.563089321978, 5744.482996530165, 5797.927383434078, 5624.368933009381, 5659.971700141473, 5669.546436285004, 5670.461306175906, 5723.599348045597], "value": 4041.1253849596696, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.0828839624528, 708.436792749764, 713.5041146403246, 730.1785491322264, 730.6529398535696, 12223.816642750344, 12625.36873156343, 13844.51544195952, 14673.77989330161, 14760.6309842254, 13044.543440979567, 13176.538821141263, 13211.40548675312, 13243.404046817892, 13271.402786874589, 633.841539615095, 659.3867703036175, 787.9133295337508, 816.2164322250617, 818.5866137600503, 728.27536601082, 806.3750593290189, 810.2464707821723, 810.7932477218301, 811.37524106438, 742.721479445208, 858.549261637639, 860.9698660546882, 863.4301361101674, 865.5455885660016, 852.0499642898155, 858.4806192253691, 859.8839156713826, 860.8923884514436, 862.6678728689437, 629.6064959400375, 636.4749082007362, 637.0, 638.3552611862007, 638.4286063972719, 11304.773904521864, 13391.799123013163, 13433.39549720265, 13468.25924574148, 13501.662458438519, 12501.124921255516, 12508.874911250861, 12663.936680316632, 12811.679708007281, 13142.802857957042, 10874.347539147677, 11160.05139563138, 11178.608748693838, 11178.888211117866, 11218.27081239725, 3528.0826984983833, 3537.7346698997676, 3689.3912504436735, 3707.2400157967554, 3724.845297957642, 91848.54075729579, 94645.0, 96272.0, 96396.0, 98733.5189972151, 10026.69919902404, 10050.64822731201, 10270.07569318765, 10270.6405275815, 10406.687799366031, 19457.708134377997, 19534.218631254877, 19567.412977610693, 19588.8041119587, 19629.21483140687, 7264.854702906018, 7394.519356241827, 7435.776926692208, 7480.139783924809, 7531.774046778605, 3890.299746045739, 4176.561461046546, 4197.748135111903, 4242.342436922282, 4243.522232950771, 3004.8948286810296, 3435.57055368079, 3468.0116166892312, 3479.9304013920087, 3483.89548313551, 551.8013515134548, 599.4604855629968, 602.0487629545308, 609.3662590905498, 612.9264488261438, 501.32571691075447, 530.7717681397037, 531.776653805395, 598.2880372356882, 642.3448082955452, 7719.922800772033, 8695.998011434252, 8861.911380886237, 9047.366684332033, 9053.366264361432, 547.8986387518322, 562.5864989232911, 596.3391953409733, 606.4117805728437, 648.4164252173082, 4316.482022157361, 4399.1641588098155, 4406.647468202536, 4475.216837053567, 4539.296409056536, 113406.0, 113429.86570134359, 116324.41837790757, 117647.0, 118421.0, 96834.51582742042, 102459.0, 104139.47930260301, 105079.94920050702, 105931.4703426478, 88145.55927220195, 88898.55550722204, 89547.0, 90672.0, 92969.5351523238, 8517.403781735218, 8568.828623427622, 8648.567571621268, 8734.56327183651, 8757.956210218908, 44520.33219501775, 48273.5172648269, 53107.73446132745, 53109.20336194883, 53357.19964200463, 76482.0, 76569.0, 77573.61213193898, 78113.21886781059, 79307.0, 10726.946365268124, 10805.783884322427, 10837.729056773644, 11189.272697274651, 11286.548538058385, 10940.015398614163, 12114.15200935926, 12116.21244619097, 12284.262944223376, 12373.876261237272, 8837.823243535222, 10146.746331341776, 11042.834357484804, 11795.053816120244, 11954.940225298818, 11141.777164456827, 11921.046316294502, 11938.820917686415, 12389.256644601353, 12411.937940310241, 10980.615678451215, 11275.492602832728, 11427.485763140512, 11552.834539238585, 11740.354280514654, 9394.95302523483, 9467.573959171992, 9565.282603804604, 9624.181944534794, 10329.276950613385, 9624.326297159132, 9930.453825039693, 9956.740231584696, 10040.196784257096, 10046.447445390573], "value": 6976.578169177776, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1581.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 173.0, 122.0, 119.0, 117.0, 118.0, 119.0, 119.0, 118.0, 117.0], "value": 129.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 112.0, 113.0, 173.0, 117.0, 122.0, 139.0, 128.0, 141.0, 113.0], "value": 130.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 198.0, 200.0, 190.0, 198.0, 193.0, 195.0, 198.0, 203.0, 195.0], "value": 196.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 61.0, 88.0, 62.0, 62.0, 77.0, 83.0, 69.0, 74.0], "value": 71.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 101.0, 104.0, 99.0, 103.0, 122.0, 72.0, 71.0, 88.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 121.0, 89.0, 75.0, 100.0, 84.0, 136.0, 130.0, 88.0, 88.0], "value": 104.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 97.0, 99.0, 100.0, 98.0, 112.0, 100.0, 97.0, 99.0], "value": 99.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 90.0, 94.0, 134.0, 72.0, 137.0, 143.0, 103.0, 96.0, 117.0], "value": 110.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 56.0, 60.0, 60.0, 61.0, 59.0, 59.0, 60.0, 89.0], "value": 61.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 66.0, 80.0, 112.0, 107.0, 71.0, 78.0, 98.0, 106.0, 103.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 89.0, 79.0, 96.0, 101.0, 100.0, 102.0, 84.0, 85.0], "value": 90.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 130.0, 152.0, 152.0, 168.0, 153.0, 147.0, 159.0, 186.0, 169.0], "value": 155.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 172.0, 158.0, 212.0, 231.0, 170.0, 167.0, 167.0, 167.0], "value": 178.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 67.0, 69.0, 72.0, 85.0, 85.0, 83.0, 84.0], "value": 74.8, "unit": "score"}]}]} 13:32:18 INFO - Return code: 0 13:32:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:32:18 ERROR - # TBPL SUCCESS # 13:32:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:32:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:32:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:32:18 INFO - 2016-12-16 13:32:18.540 screenresolution[1656:11012] starting screenresolution argv=screenresolution get 13:32:18 INFO - 2016-12-16 13:32:18.543 screenresolution[1656:11012] Display 0: 1600x1200x32@60 13:32:18 INFO - 2016-12-16 13:32:18.549 screenresolution[1657:11016] starting screenresolution argv=screenresolution list 13:32:18 INFO - Available Modes on Display 0 13:32:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:32:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:32:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:32:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:32:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:32:18 INFO - Intel Iris: 13:32:18 INFO - Chipset Model: Intel Iris 13:32:18 INFO - Type: GPU 13:32:18 INFO - Bus: Built-In 13:32:18 INFO - VRAM (Dynamic, Max): 1536 MB 13:32:18 INFO - Vendor: Intel (0x8086) 13:32:18 INFO - Device ID: 0x0a2e 13:32:18 INFO - Revision ID: 0x0009 13:32:18 INFO - Displays: 13:32:18 INFO - Display: 13:32:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:32:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:32:18 INFO - Display Serial Number: 1600x1200 60 13:32:18 INFO - Main Display: Yes 13:32:18 INFO - Mirror: Off 13:32:18 INFO - Online: Yes 13:32:18 INFO - Rotation: Supported 13:32:18 INFO - Return code: 0 13:32:18 INFO - Running post-action listener: _resource_record_post_action 13:32:18 INFO - [mozharness: 2016-12-16 21:32:18.787179Z] Finished run-tests step (success) 13:32:18 INFO - Running post-run listener: _resource_record_post_run 13:32:18 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 39655936; Write bytes: 660918784; Read time: 385; Write time: 2015 13:32:18 INFO - TinderboxPrint: CPU usage
54.5% 13:32:18 INFO - TinderboxPrint: I/O read bytes / time
39,655,936 / 385 13:32:18 INFO - TinderboxPrint: I/O write bytes / time
660,918,784 / 2,015 13:32:18 INFO - TinderboxPrint: CPU idle
2,203.5 (45.5%) 13:32:18 INFO - TinderboxPrint: CPU system
981.5 (20.3%) 13:32:18 INFO - TinderboxPrint: CPU user
1,660.8 (34.3%) 13:32:18 INFO - TinderboxPrint: Swap in / out
388,796,416 / 0 13:32:18 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 339468800; Write bytes: 334694400; Read time: 26670; Write time: 645 13:32:18 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 33432064; Write bytes: 308533760; Read time: 308; Write time: 1319 13:32:19 INFO - Running post-run listener: _upload_blobber_files 13:32:19 INFO - Blob upload gear active. 13:32:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:32:19 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 13:32:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:32:19 INFO - (blobuploader) - INFO - Open directory for files ... 13:32:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:32:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:32:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:32:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:32:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:32:20 INFO - (blobuploader) - INFO - Done attempting. 13:32:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:32:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:32:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:32:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:32:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:32:21 INFO - (blobuploader) - INFO - Done attempting. 13:32:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:32:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:32:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:32:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:32:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:32:23 INFO - (blobuploader) - INFO - Done attempting. 13:32:23 INFO - (blobuploader) - INFO - Iteration through files over. 13:32:23 INFO - Return code: 0 13:32:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:32:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:32:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b09a6dd1c9f5cb6922bac2ce2cb0e4f0089e6a8e01688d658696548854e79ddec95af5afb244509f6c2460a3ec9a97f9cb49a6a1a38f607d08a7b4b95b682d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3af8f8ae7a0729dbfe4b546fcf85e048be1a003ba0f9edc2fd3beea0ca63be60532d22b318f3da3897e2ec783a7dd1fe10db65603c624193f8b1ba1e1ecb8d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5d7b71f55d1f2259ec21829a57c3436362caf7ad54dd59a7c083646f4f273e6a47c2ec2ac1b9c1d53687963b693c897a832cc41f9aafc7f7fae631725e61247"} 13:32:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:32:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:32:23 INFO - Contents: 13:32:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b09a6dd1c9f5cb6922bac2ce2cb0e4f0089e6a8e01688d658696548854e79ddec95af5afb244509f6c2460a3ec9a97f9cb49a6a1a38f607d08a7b4b95b682d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3af8f8ae7a0729dbfe4b546fcf85e048be1a003ba0f9edc2fd3beea0ca63be60532d22b318f3da3897e2ec783a7dd1fe10db65603c624193f8b1ba1e1ecb8d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5d7b71f55d1f2259ec21829a57c3436362caf7ad54dd59a7c083646f4f273e6a47c2ec2ac1b9c1d53687963b693c897a832cc41f9aafc7f7fae631725e61247"} 13:32:23 INFO - Running post-run listener: copy_logs_to_upload_dir 13:32:23 INFO - Copying logs to upload dir... 13:32:23 INFO - mkdir: /builds/slave/test/build/upload/logs 13:32:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.232728 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-16 13:32:24.026494) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 13:32:24.030092) ========= 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.WbQkiQST7J/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.x9egy9eV2G/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b09a6dd1c9f5cb6922bac2ce2cb0e4f0089e6a8e01688d658696548854e79ddec95af5afb244509f6c2460a3ec9a97f9cb49a6a1a38f607d08a7b4b95b682d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3af8f8ae7a0729dbfe4b546fcf85e048be1a003ba0f9edc2fd3beea0ca63be60532d22b318f3da3897e2ec783a7dd1fe10db65603c624193f8b1ba1e1ecb8d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5d7b71f55d1f2259ec21829a57c3436362caf7ad54dd59a7c083646f4f273e6a47c2ec2ac1b9c1d53687963b693c897a832cc41f9aafc7f7fae631725e61247"} build_url:https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010954 build_url: 'https://queue.taskcluster.net/v1/task/G6AJhiCzSYencZg8Rno6wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b09a6dd1c9f5cb6922bac2ce2cb0e4f0089e6a8e01688d658696548854e79ddec95af5afb244509f6c2460a3ec9a97f9cb49a6a1a38f607d08a7b4b95b682d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3af8f8ae7a0729dbfe4b546fcf85e048be1a003ba0f9edc2fd3beea0ca63be60532d22b318f3da3897e2ec783a7dd1fe10db65603c624193f8b1ba1e1ecb8d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5d7b71f55d1f2259ec21829a57c3436362caf7ad54dd59a7c083646f4f273e6a47c2ec2ac1b9c1d53687963b693c897a832cc41f9aafc7f7fae631725e61247"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 13:32:24.063359) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 13:32:24.063651) ========= 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.WbQkiQST7J/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.x9egy9eV2G/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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.005093 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 13:32:24.112411) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 13:32:24.112709) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 13:32:24.113047) ========= ========= Total master_lag: 0.69 =========