builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0043 starttime: 1475362836.63 results: success (0) buildid: 20161001140414 builduid: cdcc2fd635824f34a030e32a71e42d1a revision: 7c576fe3279d87543f0a03b844eba7bc215e17f1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-01 16:00:36.631762) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-01 16:00:36.632191) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-01 16:00:36.632464) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-01 16:00:36.691354) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 16:00:36.691606) ========= 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.Z3IFf4QAvz/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.esJgi7MNTH/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-01 16:00:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.2M=0s 2016-10-01 16:00:36 (29.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.154071 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 16:00:36.867635) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 16:00:36.867976) ========= 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.Z3IFf4QAvz/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.esJgi7MNTH/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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.038124 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 16:00:36.927271) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-01 16:00:36.927622) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7c576fe3279d87543f0a03b844eba7bc215e17f1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 7c576fe3279d87543f0a03b844eba7bc215e17f1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Z3IFf4QAvz/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.esJgi7MNTH/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-01 16:00:37,010 truncating revision to first 12 chars 2016-10-01 16:00:37,010 Setting DEBUG logging. 2016-10-01 16:00:37,011 attempt 1/10 2016-10-01 16:00:37,011 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7c576fe3279d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-01 16:00:38,383 unpacking tar archive at: fx-team-7c576fe3279d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.631867 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-01 16:00:38.581232) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-01 16:00:38.581544) ========= script_repo_revision: 7c576fe3279d87543f0a03b844eba7bc215e17f1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-01 16:00:38.581940) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-01 16:00:38.582254) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-01 16:00:38.605760) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-01 16:00:38.606465) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Z3IFf4QAvz/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.esJgi7MNTH/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 16:00:38 INFO - MultiFileLogger online at 20161001 16:00:38 in /builds/slave/test 16:00:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:00:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:00:38 INFO - {'append_to_log': False, 16:00:38 INFO - 'base_work_dir': '/builds/slave/test', 16:00:38 INFO - 'blob_upload_branch': 'Fx-Team', 16:00:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:00:38 INFO - 'branch': 'Fx-Team', 16:00:38 INFO - 'buildbot_json_path': 'buildprops.json', 16:00:38 INFO - 'config_files': ('talos/mac_config.py',), 16:00:38 INFO - 'default_actions': ('clobber', 16:00:38 INFO - 'read-buildbot-config', 16:00:38 INFO - 'download-and-extract', 16:00:38 INFO - 'populate-webroot', 16:00:38 INFO - 'create-virtualenv', 16:00:38 INFO - 'install', 16:00:38 INFO - 'run-tests'), 16:00:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:00:38 INFO - 'download_minidump_stackwalk': True, 16:00:38 INFO - 'download_symbols': 'ondemand', 16:00:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:00:38 INFO - 'tooltool.py': '/tools/tooltool.py', 16:00:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:00:38 INFO - '/tools/misc-python/virtualenv.py')}, 16:00:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:00:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:00:38 INFO - 'installer_path': 'installer.exe', 16:00:38 INFO - 'log_level': 'info', 16:00:38 INFO - 'log_name': 'talos', 16:00:38 INFO - 'log_to_console': True, 16:00:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:00:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:00:38 INFO - 'opt_config_files': (), 16:00:38 INFO - 'pip_index': False, 16:00:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:00:38 INFO - 'cmd': ('bash', 16:00:38 INFO - '-c', 16:00:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:00:38 INFO - 'enabled': True, 16:00:38 INFO - 'halt_on_failure': False, 16:00:38 INFO - 'name': 'check_screen_resolution'},), 16:00:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:00:38 INFO - 'cmd': ('bash', 16:00:38 INFO - '-c', 16:00:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:00:38 INFO - 'enabled': True, 16:00:38 INFO - 'halt_on_failure': False, 16:00:38 INFO - 'name': 'check_screen_resolution'},), 16:00:38 INFO - 'run_cmd_checks_enabled': True, 16:00:38 INFO - 'sps_profile': False, 16:00:38 INFO - 'sps_profile_interval': 0, 16:00:38 INFO - 'suite': 'dromaeojs', 16:00:38 INFO - 'system_bits': '32', 16:00:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:00:38 INFO - 'title': 't-yosemite-r7-0043', 16:00:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:00:38 INFO - 'use_talos_json': True, 16:00:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:00:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:00:38 INFO - 'work_dir': 'build'} 16:00:38 INFO - [mozharness: 2016-10-01 23:00:38.813474Z] Running clobber step. 16:00:38 INFO - Running pre-action listener: _resource_record_pre_action 16:00:38 INFO - Running main action method: clobber 16:00:38 INFO - rmtree: /builds/slave/test/build 16:00:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:00:40 INFO - Running post-action listener: _resource_record_post_action 16:00:40 INFO - [mozharness: 2016-10-01 23:00:40.996604Z] Finished clobber step (success) 16:00:40 INFO - [mozharness: 2016-10-01 23:00:40.996730Z] Running read-buildbot-config step. 16:00:40 INFO - Running pre-action listener: _resource_record_pre_action 16:00:40 INFO - Running main action method: read_buildbot_config 16:00:40 INFO - Using buildbot properties: 16:00:40 INFO - { 16:00:40 INFO - "project": "", 16:00:40 INFO - "product": "firefox", 16:00:40 INFO - "script_repo_revision": "production", 16:00:40 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:00:40 INFO - "repository": "", 16:00:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:00:40 INFO - "buildid": "20161001140414", 16:00:40 INFO - "pgo_build": "False", 16:00:40 INFO - "basedir": "/builds/slave/test", 16:00:40 INFO - "buildnumber": 845, 16:00:40 INFO - "slavename": "t-yosemite-r7-0043", 16:00:40 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:00:40 INFO - "platform": "yosemite_r7", 16:00:40 INFO - "scheduler": "tests-fx-team-macosx64-talos", 16:00:40 INFO - "branch": "fx-team", 16:00:40 INFO - "repo_path": "integration/fx-team", 16:00:40 INFO - "revision": "7c576fe3279d87543f0a03b844eba7bc215e17f1", 16:00:40 INFO - "stage_platform": "macosx64", 16:00:40 INFO - "builduid": "cdcc2fd635824f34a030e32a71e42d1a", 16:00:40 INFO - "slavebuilddir": "test" 16:00:40 INFO - } 16:00:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 16:00:40 INFO - Running post-action listener: _resource_record_post_action 16:00:41 INFO - [mozharness: 2016-10-01 23:00:41.000072Z] Finished read-buildbot-config step (success) 16:00:41 INFO - [mozharness: 2016-10-01 23:00:41.000188Z] Running download-and-extract step. 16:00:41 INFO - Running pre-action listener: _resource_record_pre_action 16:00:41 INFO - Running main action method: download_and_extract 16:00:41 INFO - mkdir: /builds/slave/test/build/tests 16:00:41 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')]} 16:00:41 INFO - trying https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 16:00:41 INFO - Downloading https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:00:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 16:00:41 INFO - Downloaded 1199 bytes. 16:00:41 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:00:41 INFO - Using the following test package requirements: 16:00:41 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:00:41 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:00:41 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 16:00:41 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:00:41 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 16:00:41 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:00:41 INFO - u'jsshell-mac.zip'], 16:00:41 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:00:41 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 16:00:41 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:00:41 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:00:41 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 16:00:41 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:00:41 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 16:00:41 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:00:41 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 16:00:41 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:00:41 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 16:00:41 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:00:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:00:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:00:41 INFO - Fetch https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:00:42 INFO - Http code: 200 16:00:42 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 16:00:42 INFO - x-amz-cf-id: qyyq4EYwAK-sTVwlsvuB8SpKtfJ7hxbmlQZ7kpNjurApYLnlyPTsCw== 16:00:42 INFO - x-amz-version-id: zTBMWC94hMeg3BXjM7tgukYe.Ilwvmov 16:00:42 INFO - Expected file size: 16475671 16:00:42 INFO - Obtained file size: 16475671 16:00:49 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:00:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:00:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:00:49 INFO - Fetch https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:00:50 INFO - Http code: 200 16:00:50 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 16:00:50 INFO - x-amz-cf-id: tLuv3Mm4wzlfj0szwz8iRI_RZXSLwknslavpn6geBqFfy8NN_ZJ-aA== 16:00:50 INFO - x-amz-version-id: zTBMWC94hMeg3BXjM7tgukYe.Ilwvmov 16:00:50 INFO - Expected file size: 16475671 16:00:50 INFO - Obtained file size: 16475671 16:00:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 16:00:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:00:56 INFO - Fetch https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 16:00:57 INFO - Http code: 200 16:00:57 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 16:00:57 INFO - x-amz-cf-id: Qw4HIMxaHPlq0huwR-86po2NhxX8r38dS56BeAfbpROq5u5BYc_W6g== 16:00:57 INFO - x-amz-version-id: r5WcgIs54UrGfXkI8ZOEaSnry7hNTbtr 16:00:58 INFO - Expected file size: 14408522 16:00:58 INFO - Obtained file size: 14408522 16:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:58 INFO - trying https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:00:58 INFO - Downloading https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 16:00:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 16:01:01 INFO - Downloaded 111809225 bytes. 16:01:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:01:01 INFO - mkdir: /builds/slave/test/properties 16:01:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:01:01 INFO - Writing to file /builds/slave/test/properties/build_url 16:01:01 INFO - Contents: 16:01:01 INFO - build_url:https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:01:02 INFO - Running post-action listener: _resource_record_post_action 16:01:02 INFO - Running post-action listener: set_extra_try_arguments 16:01:02 INFO - [mozharness: 2016-10-01 23:01:02.433369Z] Finished download-and-extract step (success) 16:01:02 INFO - [mozharness: 2016-10-01 23:01:02.433481Z] Running populate-webroot step. 16:01:02 INFO - Running pre-action listener: _resource_record_pre_action 16:01:02 INFO - Running main action method: populate_webroot 16:01:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:01:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:01:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:01:02 INFO - '--tppagecycles', 16:01:02 INFO - '7'], 16:01:02 INFO - 'tests': ['tsvgm']}}, 16:01:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:01:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:01:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:01:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:01:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:01:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:01:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:02 INFO - 'talos_options': ['--disable-e10s'], 16:01:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:02 INFO - 'talos_options': ['--disable-e10s'], 16:01:02 INFO - 'tests': ['damp', 'tps']}, 16:01:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:02 INFO - 'tests': ['damp', 'tps']}, 16:01:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:01:02 INFO - 'tests': ['dromaeo_dom']}, 16:01:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:01:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:01:02 INFO - 'tests': ['basic_compositor_video']}, 16:01:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:01:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:01:02 INFO - 'tests': ['a11yr', 16:01:02 INFO - 'ts_paint', 16:01:02 INFO - 'tpaint', 16:01:02 INFO - 'sessionrestore', 16:01:02 INFO - 'sessionrestore_no_auto_restore', 16:01:02 INFO - 'tabpaint']}, 16:01:02 INFO - 'other-e10s': {'tests': ['a11yr', 16:01:02 INFO - 'ts_paint', 16:01:02 INFO - 'tpaint', 16:01:02 INFO - 'sessionrestore', 16:01:02 INFO - 'sessionrestore_no_auto_restore', 16:01:02 INFO - 'tabpaint']}, 16:01:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:01:02 INFO - 'ts_paint', 16:01:02 INFO - 'tpaint', 16:01:02 INFO - 'sessionrestore', 16:01:02 INFO - 'sessionrestore_no_auto_restore', 16:01:02 INFO - 'tabpaint']}, 16:01:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:01:02 INFO - 'ts_paint', 16:01:02 INFO - 'tpaint', 16:01:02 INFO - 'sessionrestore', 16:01:02 INFO - 'sessionrestore_no_auto_restore', 16:01:02 INFO - 'tabpaint']}, 16:01:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:01:02 INFO - 'tests': ['a11yr', 16:01:02 INFO - 'ts_paint', 16:01:02 INFO - 'tpaint', 16:01:02 INFO - 'sessionrestore', 16:01:02 INFO - 'sessionrestore_no_auto_restore', 16:01:02 INFO - 'tabpaint']}, 16:01:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:01:02 INFO - 'tests': ['a11yr', 16:01:02 INFO - 'ts_paint', 16:01:02 INFO - 'tpaint', 16:01:02 INFO - 'sessionrestore', 16:01:02 INFO - 'sessionrestore_no_auto_restore', 16:01:02 INFO - 'tabpaint']}, 16:01:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:01:02 INFO - 'tests': ['tsvgx', 16:01:02 INFO - 'tsvgr_opacity', 16:01:02 INFO - 'tart', 16:01:02 INFO - 'tscrollx', 16:01:02 INFO - 'cart']}, 16:01:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:01:02 INFO - 'tsvgr_opacity', 16:01:02 INFO - 'tart', 16:01:02 INFO - 'tscrollx', 16:01:02 INFO - 'cart']}, 16:01:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:02 INFO - 'talos_options': ['--disable-e10s'], 16:01:02 INFO - 'tests': ['tp5o']}, 16:01:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:02 INFO - 'tests': ['tp5o']}, 16:01:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:02 INFO - 'talos_options': ['--disable-e10s', 16:01:02 INFO - '--xperf_path', 16:01:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:02 INFO - 'tests': ['tp5n']}, 16:01:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:02 INFO - 'talos_options': ['--xperf_path', 16:01:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:02 INFO - 'tests': ['tp5n']}}, 16:01:02 INFO - 'talos.zip': {'path': '', 16:01:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:01:02 INFO - Running post-action listener: _resource_record_post_action 16:01:02 INFO - [mozharness: 2016-10-01 23:01:02.451770Z] Finished populate-webroot step (success) 16:01:02 INFO - [mozharness: 2016-10-01 23:01:02.451896Z] Running create-virtualenv step. 16:01:02 INFO - Running pre-action listener: _resource_record_pre_action 16:01:02 INFO - Running main action method: create_virtualenv 16:01:02 INFO - Creating virtualenv /builds/slave/test/build/venv 16:01:02 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:01:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:01:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:01:02 INFO - Using real prefix '/tools/python27' 16:01:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:01:03 INFO - Installing distribute.............................................................................................................................................................................................done. 16:01:06 INFO - Installing pip.................done. 16:01:06 INFO - Return code: 0 16:01:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:01:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:01:06 INFO - Reading from file tmpfile_stdout 16:01:06 INFO - Output received: 16:01:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 16:01:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01: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')]} 16:01:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101944490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e1490320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101758d30>, '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 0x10157edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101750b20>, '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.Z3IFf4QAvz/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.esJgi7MNTH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 16:01:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:01:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:01:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3IFf4QAvz/Render', 16:01:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:06 INFO - 'HOME': '/Users/cltbld', 16:01:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:06 INFO - 'LOGNAME': 'cltbld', 16:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:06 INFO - 'MOZ_NO_REMOTE': '1', 16:01:06 INFO - 'NO_EM_RESTART': '1', 16:01:06 INFO - 'PAGER': '/bin/cat', 16:01:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:06 INFO - 'PWD': '/builds/slave/test', 16:01:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:06 INFO - 'SHELL': '/bin/bash', 16:01:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.esJgi7MNTH/Listeners', 16:01:06 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 16:01:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:06 INFO - 'USER': 'cltbld', 16:01:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:06 INFO - 'XPC_FLAGS': '0x0', 16:01:06 INFO - 'XPC_SERVICE_NAME': '0', 16:01:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:01:06 INFO - Cleaning up... 16:01:06 INFO - Return code: 0 16:01:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01: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')]} 16:01:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101944490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e1490320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101758d30>, '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 0x10157edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101750b20>, '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.Z3IFf4QAvz/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.esJgi7MNTH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 16:01:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:01:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:01:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3IFf4QAvz/Render', 16:01:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:06 INFO - 'HOME': '/Users/cltbld', 16:01:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:06 INFO - 'LOGNAME': 'cltbld', 16:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:06 INFO - 'MOZ_NO_REMOTE': '1', 16:01:06 INFO - 'NO_EM_RESTART': '1', 16:01:06 INFO - 'PAGER': '/bin/cat', 16:01:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:06 INFO - 'PWD': '/builds/slave/test', 16:01:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:06 INFO - 'SHELL': '/bin/bash', 16:01:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.esJgi7MNTH/Listeners', 16:01:06 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 16:01:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:06 INFO - 'USER': 'cltbld', 16:01:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:06 INFO - 'XPC_FLAGS': '0x0', 16:01:06 INFO - 'XPC_SERVICE_NAME': '0', 16:01:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:07 INFO - Downloading/unpacking psutil>=3.1.1 16:01:07 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 16:01:07 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 16:01:07 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 16:01:07 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 16:01:07 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 16:01:07 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 16:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:01:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:10 INFO - Installing collected packages: psutil 16:01:10 INFO - Running setup.py install for psutil 16:01:10 INFO - building 'psutil._psutil_osx' extension 16:01:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:01:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:01:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:01:11 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:01:11 INFO - building 'psutil._psutil_posix' extension 16:01:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:01:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:01:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:01:11 INFO - ^ 16:01:11 INFO - 1 warning generated. 16:01:11 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:01:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:12 INFO - Successfully installed psutil 16:01:12 INFO - Cleaning up... 16:01:12 INFO - Return code: 0 16:01:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01: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')]} 16:01:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:12 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 0x101944490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e1490320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101758d30>, '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 0x10157edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101750b20>, '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.Z3IFf4QAvz/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.esJgi7MNTH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 16:01:12 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 16:01:12 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 16:01:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3IFf4QAvz/Render', 16:01:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:12 INFO - 'HOME': '/Users/cltbld', 16:01:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:12 INFO - 'LOGNAME': 'cltbld', 16:01:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:12 INFO - 'MOZ_NO_REMOTE': '1', 16:01:12 INFO - 'NO_EM_RESTART': '1', 16:01:12 INFO - 'PAGER': '/bin/cat', 16:01:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:12 INFO - 'PWD': '/builds/slave/test', 16:01:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:12 INFO - 'SHELL': '/bin/bash', 16:01:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.esJgi7MNTH/Listeners', 16:01:12 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 16:01:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:12 INFO - 'USER': 'cltbld', 16:01:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:12 INFO - 'XPC_FLAGS': '0x0', 16:01:12 INFO - 'XPC_SERVICE_NAME': '0', 16:01:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:01:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:01:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:01:14 INFO - Installing collected packages: mozsystemmonitor 16:01:14 INFO - Running setup.py install for mozsystemmonitor 16:01:15 INFO - Successfully installed mozsystemmonitor 16:01:15 INFO - Cleaning up... 16:01:15 INFO - Return code: 0 16:01:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:01:15 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')]} 16:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:15 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')]} 16:01:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:15 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 0x101944490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e1490320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101758d30>, '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 0x10157edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101750b20>, '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.Z3IFf4QAvz/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.esJgi7MNTH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 16:01:15 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 16:01:15 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 16:01:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3IFf4QAvz/Render', 16:01:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:15 INFO - 'HOME': '/Users/cltbld', 16:01:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:15 INFO - 'LOGNAME': 'cltbld', 16:01:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:15 INFO - 'MOZ_NO_REMOTE': '1', 16:01:15 INFO - 'NO_EM_RESTART': '1', 16:01:15 INFO - 'PAGER': '/bin/cat', 16:01:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:15 INFO - 'PWD': '/builds/slave/test', 16:01:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:15 INFO - 'SHELL': '/bin/bash', 16:01:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.esJgi7MNTH/Listeners', 16:01:15 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 16:01:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:15 INFO - 'USER': 'cltbld', 16:01:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:15 INFO - 'XPC_FLAGS': '0x0', 16:01:15 INFO - 'XPC_SERVICE_NAME': '0', 16:01:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:15 INFO - Downloading/unpacking jsonschema==2.5.1 16:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:01:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:18 INFO - Downloading functools32-3.2.3-2.tar.gz 16:01:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:01:18 INFO - warning: no files found matching '*.txt' 16:01:18 INFO - no previously-included directories found matching 'build' 16:01:18 INFO - no previously-included directories found matching 'dist' 16:01:18 INFO - no previously-included directories found matching '.git*' 16:01:18 INFO - Installing collected packages: jsonschema, functools32 16:01:18 INFO - Running setup.py install for functools32 16:01:18 INFO - warning: no files found matching '*.txt' 16:01:18 INFO - no previously-included directories found matching 'build' 16:01:18 INFO - no previously-included directories found matching 'dist' 16:01:18 INFO - no previously-included directories found matching '.git*' 16:01:18 INFO - Successfully installed jsonschema functools32 16:01:18 INFO - Cleaning up... 16:01:18 INFO - Return code: 0 16:01:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101944490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e1490320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101758d30>, '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 0x10157edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101750b20>, '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.Z3IFf4QAvz/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.esJgi7MNTH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 16:01:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:01:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:01:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3IFf4QAvz/Render', 16:01:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:18 INFO - 'HOME': '/Users/cltbld', 16:01:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:18 INFO - 'LOGNAME': 'cltbld', 16:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:18 INFO - 'MOZ_NO_REMOTE': '1', 16:01:18 INFO - 'NO_EM_RESTART': '1', 16:01:18 INFO - 'PAGER': '/bin/cat', 16:01:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:18 INFO - 'PWD': '/builds/slave/test', 16:01:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:18 INFO - 'SHELL': '/bin/bash', 16:01:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.esJgi7MNTH/Listeners', 16:01:18 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 16:01:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:18 INFO - 'USER': 'cltbld', 16:01:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:18 INFO - 'XPC_FLAGS': '0x0', 16:01:18 INFO - 'XPC_SERVICE_NAME': '0', 16:01:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:01:18 INFO - Cleaning up... 16:01:18 INFO - Return code: 0 16:01:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101944490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e1490320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101758d30>, '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 0x10157edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101750b20>, '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.Z3IFf4QAvz/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.esJgi7MNTH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 16:01:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:01:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:01:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3IFf4QAvz/Render', 16:01:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:18 INFO - 'HOME': '/Users/cltbld', 16:01:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:18 INFO - 'LOGNAME': 'cltbld', 16:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:18 INFO - 'MOZ_NO_REMOTE': '1', 16:01:18 INFO - 'NO_EM_RESTART': '1', 16:01:18 INFO - 'PAGER': '/bin/cat', 16:01:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:18 INFO - 'PWD': '/builds/slave/test', 16:01:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:18 INFO - 'SHELL': '/bin/bash', 16:01:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.esJgi7MNTH/Listeners', 16:01:18 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 16:01:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:18 INFO - 'USER': 'cltbld', 16:01:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:18 INFO - 'XPC_FLAGS': '0x0', 16:01:18 INFO - 'XPC_SERVICE_NAME': '0', 16:01:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:19 INFO - Downloading/unpacking blobuploader==1.2.4 16:01:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:21 INFO - Downloading blobuploader-1.2.4.tar.gz 16:01:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:01:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:01:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:22 INFO - Downloading docopt-0.6.1.tar.gz 16:01:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:01:23 INFO - Installing collected packages: blobuploader, requests, docopt 16:01:23 INFO - Running setup.py install for blobuploader 16:01:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:01:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:01:23 INFO - Running setup.py install for requests 16:01:23 INFO - Running setup.py install for docopt 16:01:23 INFO - Successfully installed blobuploader requests docopt 16:01:23 INFO - Cleaning up... 16:01:23 INFO - Return code: 0 16:01:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101944490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e1490320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101758d30>, '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 0x10157edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101750b20>, '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.Z3IFf4QAvz/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.esJgi7MNTH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 16:01:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:01:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3IFf4QAvz/Render', 16:01:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:23 INFO - 'HOME': '/Users/cltbld', 16:01:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:23 INFO - 'LOGNAME': 'cltbld', 16:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:23 INFO - 'MOZ_NO_REMOTE': '1', 16:01:23 INFO - 'NO_EM_RESTART': '1', 16:01:23 INFO - 'PAGER': '/bin/cat', 16:01:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:23 INFO - 'PWD': '/builds/slave/test', 16:01:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:23 INFO - 'SHELL': '/bin/bash', 16:01:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.esJgi7MNTH/Listeners', 16:01:23 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 16:01:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:23 INFO - 'USER': 'cltbld', 16:01:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:23 INFO - 'XPC_FLAGS': '0x0', 16:01:23 INFO - 'XPC_SERVICE_NAME': '0', 16:01:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:24 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-NJKGTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:24 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-wvnYTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:24 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-caaKjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:24 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-PCCUua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:24 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-cIIbBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:24 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-gYsj8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:24 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-jWU37e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:24 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-gGJk7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:25 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-YEwFNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:25 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-7G08OX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:25 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-x3Hycf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:25 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-mEjGg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:25 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-qOSIua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:25 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-3EhT2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:25 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-UYVVD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:25 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-Gr8Wvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:25 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-zEf70K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:01:25 INFO - Running setup.py install for manifestparser 16:01:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:01:26 INFO - Running setup.py install for mozcrash 16:01:26 INFO - Running setup.py install for mozdebug 16:01:26 INFO - Running setup.py install for mozdevice 16:01:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:01:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:01:26 INFO - Running setup.py install for mozfile 16:01:26 INFO - Running setup.py install for mozhttpd 16:01:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:01:26 INFO - Running setup.py install for mozinfo 16:01:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:01:26 INFO - Running setup.py install for mozInstall 16:01:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:01:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:01:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:01:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:01:27 INFO - Running setup.py install for mozleak 16:01:27 INFO - Running setup.py install for mozlog 16:01:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:01:27 INFO - Running setup.py install for moznetwork 16:01:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:01:27 INFO - Running setup.py install for mozprocess 16:01:27 INFO - Running setup.py install for mozprofile 16:01:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:01:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:01:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:01:27 INFO - Running setup.py install for mozrunner 16:01:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:01:28 INFO - Running setup.py install for mozscreenshot 16:01:28 INFO - Running setup.py install for moztest 16:01:28 INFO - Running setup.py install for mozversion 16:01:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:01:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:01:28 INFO - Cleaning up... 16:01:28 INFO - Return code: 0 16:01:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101944490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e1490320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101758d30>, '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 0x10157edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101750b20>, '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.Z3IFf4QAvz/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.esJgi7MNTH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 16:01:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:01:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:01:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3IFf4QAvz/Render', 16:01:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:28 INFO - 'HOME': '/Users/cltbld', 16:01:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:28 INFO - 'LOGNAME': 'cltbld', 16:01:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:28 INFO - 'MOZ_NO_REMOTE': '1', 16:01:28 INFO - 'NO_EM_RESTART': '1', 16:01:28 INFO - 'PAGER': '/bin/cat', 16:01:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:28 INFO - 'PWD': '/builds/slave/test', 16:01:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:28 INFO - 'SHELL': '/bin/bash', 16:01:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.esJgi7MNTH/Listeners', 16:01:28 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 16:01:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:28 INFO - 'USER': 'cltbld', 16:01:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:28 INFO - 'XPC_FLAGS': '0x0', 16:01:28 INFO - 'XPC_SERVICE_NAME': '0', 16:01:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:28 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-C0XtFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:28 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:28 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-rfzJ82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:29 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-lin82g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:29 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-BxqXNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:29 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-tkVrxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:29 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-D9C9aS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:29 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-FsIVTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:29 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-hBYLwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:29 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-aXTKEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:29 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-kryGyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:29 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-URMSuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:29 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)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:29 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-vko5DU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:30 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-YjgynP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:30 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-NEx91r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:30 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-xyFJaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:30 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-7sBIdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:30 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-01aXuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:01:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:01:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:01:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:01:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:01:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:01:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:33 INFO - Downloading blessings-1.6.tar.gz 16:01:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:01:33 INFO - Installing collected packages: blessings 16:01:33 INFO - Running setup.py install for blessings 16:01:33 INFO - Successfully installed blessings 16:01:33 INFO - Cleaning up... 16:01:33 INFO - Return code: 0 16:01:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:01:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:01:33 INFO - Reading from file tmpfile_stdout 16:01:33 INFO - Current package versions: 16:01:33 INFO - blessings == 1.6 16:01:33 INFO - blobuploader == 1.2.4 16:01:33 INFO - docopt == 0.6.1 16:01:33 INFO - functools32 == 3.2.3-2 16:01:33 INFO - jsonschema == 2.5.1 16:01:33 INFO - manifestparser == 1.1 16:01:33 INFO - mozInstall == 1.12 16:01:33 INFO - mozcrash == 0.17 16:01:33 INFO - mozdebug == 0.1 16:01:33 INFO - mozdevice == 0.48 16:01:33 INFO - mozfile == 1.2 16:01:33 INFO - mozhttpd == 0.7 16:01:33 INFO - mozinfo == 0.9 16:01:33 INFO - mozleak == 0.1 16:01:33 INFO - mozlog == 3.4 16:01:33 INFO - moznetwork == 0.27 16:01:33 INFO - mozprocess == 0.23 16:01:33 INFO - mozprofile == 0.28 16:01:33 INFO - mozrunner == 6.12 16:01:33 INFO - mozscreenshot == 0.1 16:01:33 INFO - mozsystemmonitor == 0.3 16:01:33 INFO - moztest == 0.7 16:01:33 INFO - mozversion == 1.4 16:01:33 INFO - psutil == 3.1.1 16:01:33 INFO - requests == 1.2.3 16:01:33 INFO - wsgiref == 0.1.2 16:01:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:33 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')]} 16:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:33 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')]} 16:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:33 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 0x101944490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e1490320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101758d30>, '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 0x10157edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101750b20>, '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.Z3IFf4QAvz/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.esJgi7MNTH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 16:01:33 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 16:01:33 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 16:01:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3IFf4QAvz/Render', 16:01:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:33 INFO - 'HOME': '/Users/cltbld', 16:01:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:33 INFO - 'LOGNAME': 'cltbld', 16:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:33 INFO - 'MOZ_NO_REMOTE': '1', 16:01:33 INFO - 'NO_EM_RESTART': '1', 16:01:33 INFO - 'PAGER': '/bin/cat', 16:01:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:33 INFO - 'PWD': '/builds/slave/test', 16:01:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:33 INFO - 'SHELL': '/bin/bash', 16:01:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.esJgi7MNTH/Listeners', 16:01:33 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 16:01:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:33 INFO - 'USER': 'cltbld', 16:01:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:33 INFO - 'XPC_FLAGS': '0x0', 16:01:33 INFO - 'XPC_SERVICE_NAME': '0', 16:01:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:33 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)) 16:01:33 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)) 16:01:33 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)) 16:01:33 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)) 16:01:33 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)) 16:01:33 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)) 16:01:33 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)) 16:01:33 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)) 16:01:33 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)) 16:01:33 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)) 16:01:33 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)) 16:01:33 INFO - Cleaning up... 16:01:34 INFO - Return code: 0 16:01:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101944490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e1490320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101758d30>, '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 0x10157edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101750b20>, '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.Z3IFf4QAvz/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.esJgi7MNTH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 16:01:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:01:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:01:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3IFf4QAvz/Render', 16:01:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:34 INFO - 'HOME': '/Users/cltbld', 16:01:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:34 INFO - 'LOGNAME': 'cltbld', 16:01:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:34 INFO - 'MOZ_NO_REMOTE': '1', 16:01:34 INFO - 'NO_EM_RESTART': '1', 16:01:34 INFO - 'PAGER': '/bin/cat', 16:01:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:34 INFO - 'PWD': '/builds/slave/test', 16:01:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:34 INFO - 'SHELL': '/bin/bash', 16:01:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.esJgi7MNTH/Listeners', 16:01:34 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 16:01:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:34 INFO - 'USER': 'cltbld', 16:01:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:34 INFO - 'XPC_FLAGS': '0x0', 16:01:34 INFO - 'XPC_SERVICE_NAME': '0', 16:01:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:01:34 INFO - Cleaning up... 16:01:34 INFO - Return code: 0 16:01:34 INFO - Running post-action listener: _resource_record_post_action 16:01:34 INFO - Running post-action listener: _start_resource_monitoring 16:01:34 INFO - Starting resource monitoring. 16:01:34 INFO - [mozharness: 2016-10-01 23:01:34.311815Z] Finished create-virtualenv step (success) 16:01:34 INFO - [mozharness: 2016-10-01 23:01:34.312775Z] Running install step. 16:01:34 INFO - Running pre-action listener: _resource_record_pre_action 16:01:34 INFO - Running main action method: install 16:01:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:01:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:01:34 INFO - Reading from file tmpfile_stdout 16:01:34 INFO - Detecting whether we're running mozinstall >=1.0... 16:01:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:01:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:01:34 INFO - Reading from file tmpfile_stdout 16:01:34 INFO - Output received: 16:01:34 INFO - Usage: mozinstall [options] installer 16:01:34 INFO - Options: 16:01:34 INFO - -h, --help show this help message and exit 16:01:34 INFO - -d DEST, --destination=DEST 16:01:34 INFO - Directory to install application into. [default: 16:01:34 INFO - "/builds/slave/test"] 16:01:34 INFO - --app=APP Application being installed. [default: firefox] 16:01:34 INFO - mkdir: /builds/slave/test/build/application 16:01:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:01:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:02:09 INFO - Reading from file tmpfile_stdout 16:02:09 INFO - Output received: 16:02:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:02:09 INFO - Running post-action listener: _resource_record_post_action 16:02:09 INFO - [mozharness: 2016-10-01 23:02:09.056417Z] Finished install step (success) 16:02:09 INFO - [mozharness: 2016-10-01 23:02:09.056562Z] Running run-tests step. 16:02:09 INFO - Running pre-action listener: _resource_record_pre_action 16:02:09 INFO - Running main action method: run_tests 16:02:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:02:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:02:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:02:09 INFO - 2016-10-01 16:02:09.071 screenresolution[1651:8212] starting screenresolution argv=screenresolution get 16:02:09 INFO - 2016-10-01 16:02:09.073 screenresolution[1651:8212] Display 0: 1600x1200x32@60 16:02:09 INFO - 2016-10-01 16:02:09.079 screenresolution[1652:8216] starting screenresolution argv=screenresolution list 16:02:09 INFO - Available Modes on Display 0 16:02:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:02:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:02:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:02:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:02:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:02:09 INFO - Intel Iris: 16:02:09 INFO - Chipset Model: Intel Iris 16:02:09 INFO - Type: GPU 16:02:09 INFO - Bus: Built-In 16:02:09 INFO - VRAM (Dynamic, Max): 1536 MB 16:02:09 INFO - Vendor: Intel (0x8086) 16:02:09 INFO - Device ID: 0x0a2e 16:02:09 INFO - Revision ID: 0x0009 16:02:09 INFO - Displays: 16:02:09 INFO - Display: 16:02:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:02:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:02:09 INFO - Display Serial Number: 1600x1200 60 16:02:09 INFO - Main Display: Yes 16:02:09 INFO - Mirror: Off 16:02:09 INFO - Online: Yes 16:02:09 INFO - Rotation: Supported 16:02:09 INFO - Return code: 0 16:02:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:02:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:02:09 INFO - Python 2.7.3 16:02:09 INFO - Return code: 0 16:02:09 INFO - grabbing minidump binary from tooltool 16:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:09 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 0x101758d30>, '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 0x10157edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101750b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:02:09 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 16:02:09 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 16:02:09 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 16:02:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:02:09 INFO - Return code: 0 16:02:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:02:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:02:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:02:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:02:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:02:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:02:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0043 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:02:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3IFf4QAvz/Render', 16:02:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:09 INFO - 'HOME': '/Users/cltbld', 16:02:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:09 INFO - 'LOGNAME': 'cltbld', 16:02:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:02:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:09 INFO - 'MOZ_NO_REMOTE': '1', 16:02:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:02:09 INFO - 'NO_EM_RESTART': '1', 16:02:09 INFO - 'PAGER': '/bin/cat', 16:02:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:09 INFO - 'PWD': '/builds/slave/test', 16:02:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:02:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:09 INFO - 'SHELL': '/bin/bash', 16:02:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.esJgi7MNTH/Listeners', 16:02:09 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 16:02:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:09 INFO - 'USER': 'cltbld', 16:02:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:09 INFO - 'XPC_FLAGS': '0x0', 16:02:09 INFO - 'XPC_SERVICE_NAME': '0', 16:02:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:02:09 INFO - mozversion application_buildid: 20161001140414 16:02:09 INFO - mozversion application_changeset: 7c576fe3279d87543f0a03b844eba7bc215e17f1 16:02:09 INFO - mozversion application_display_name: Nightly 16:02:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:02:09 INFO - mozversion application_name: Firefox 16:02:09 INFO - mozversion application_remotingname: firefox 16:02:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:02:09 INFO - mozversion application_vendor: Mozilla 16:02:09 INFO - mozversion application_version: 52.0a1 16:02:09 INFO - mozversion platform_buildid: 20161001140414 16:02:09 INFO - mozversion platform_changeset: 7c576fe3279d87543f0a03b844eba7bc215e17f1 16:02:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:02:09 INFO - mozversion platform_version: 52.0a1 16:02:09 INFO - using testdate: 1475362929 16:02:09 INFO - actual date: 1475362929 16:02:09 INFO - starting webserver on 'localhost:49274' 16:02:09 INFO - SUITE-START | Running 2 tests 16:02:09 INFO - TEST-START | dromaeo_css 16:02:09 INFO - operating with platform_type : mac_ 16:02:09 INFO - Initialising browser for dromaeo_css test... 16:02:09 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmplF3o8A/profile http://localhost:49274/getInfo.html) 16:02:12 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 16:02:12 INFO - PROCESS | 1662 | colorDepth:24 16:02:12 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 16:02:12 INFO - PROCESS | 1662 | __metrics 16:02:12 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:02:12 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:02:12 INFO - TEST-INFO | 1662: exit 0 16:02:12 INFO - Browser initialized. 16:02:12 INFO - Running cycle 1/1 for dromaeo_css test... 16:02:12 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmplF3o8A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:02:24 INFO - PROCESS | 1665 | RSS: Main: 236134400 16:02:24 INFO - PROCESS | 1665 | 16:03:16 INFO - PROCESS | 1665 | 1475362996547 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] 16:05:29 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 16:05:31 INFO - PROCESS | 1665 | RSS: Main: 369491968 16:05:31 INFO - PROCESS | 1665 | 16:08:34 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 16:08:34 INFO - PROCESS | 1665 | RSS: Main: 374005760 16:08:34 INFO - PROCESS | 1665 | 16:11:37 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 16:11:37 INFO - PROCESS | 1665 | RSS: Main: 334585856 16:11:37 INFO - PROCESS | 1665 | 16:14:40 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 16:14:40 INFO - PROCESS | 1665 | RSS: Main: 335302656 16:14:40 INFO - PROCESS | 1665 | 16:17:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 16:17:45 INFO - PROCESS | 1665 | RSS: Main: 327770112 16:17:45 INFO - PROCESS | 1665 | 16:20:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 16:20:48 INFO - PROCESS | 1665 | RSS: Main: 448081920 16:20:48 INFO - PROCESS | 1665 | 16:20:48 INFO - PROCESS | 1665 | __start_tp_report 16:20:48 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 16:20:48 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 16:20:48 INFO - PROCESS | 1665 | |i|pagename|runs| 16:20:48 INFO - PROCESS | 1665 | |0;dojo.html;3285.605727312722;3287.1124796304985;3311.4536101543245;3355.4463513520272;3372.7133193678537;5568.582356323272;5609.607327487072;5677.403872593381;5722.570807189457;5733.598648094629;5657.9717101414435;5668.546516278698;5770.596058275923;5778.653280803154;5809.419058094199;4181.3309870420735;4264.189803937257;4283.657307415399;5186.429492755794;5225.137852254393;4657.254839225725;5072.112380333445;5257.421683614818;5262.81580144695;5284.445133260991;5064.620153488503;5222.791088356461;5238.292830467914;5258.605604579653;5341.4658534146665;5110.310108135392;5147.562457191143;5151.021305951857;5160.071187186305;5173.586113110942;5070.163423035195;5117.948820511784;5129.281900533928;5147.22791581262;5152.819651312186;4695.5304469552775;5627.493525582717;5652.971735141339;5663.405342439067;5673.177389278531;6097.857415703;6119.958356786701;6144.586745048664;6146.4775494082605;6188.535859810532;6167.599106058092;6190.81427557174;6251.968740156316;6307.842303942438;6319.083732858756;5735.397783232742;5753.367129615739;5797.710114494258;5811.593188476808;5812.88374232513;6168.9691551541955;6239.812805615838;6262.6868656566985;6279.246490421178;6337.841553961188;6145.525073982212;6147.508199344042;6169.321374648785;6174.9691251544355;6185.536084793658;6268.780592679234;6279.780207692709;6281.780137695158;6298.401651843067;6380.298167201604;5909.793157239562;6026.758929642853;6112.235970503687;6124.836281106575;6205.81382558524;5963.850903727442;5992.970035149883;6007.729652165663;6015.518758499309;6062.757489700362;4754.381930349033;4757.762111894392;4759.405074365704;4767.642426818003;4836.105320515716;4250.213710463512;4251.319788833772;4268.957310426918;4397.164538737629;4444.533324000998;5700.287464066992;5744.425557444222;5757.539396848242;5765.365809760923;5834.416558344132;6224.350884863449;6265.592736472115;6275.215598050244;6315.336889626615;6316.684165791784;5774.82675519735;5783.537317014713;5788.797392091256;5813.505852002627;5831.2127862738835;5273.499017593323;5277.577793776489;5308.416074231832;5359.249705041298;5371.060064488777;6229.283632382334;6257.624542527463;6260.937390626035;6289.119523266655;6305.747770089145;6223.937760622517;6290.622562646256;6299.338569450325;6305.243370795534;6315.652639104894;6223;6286.811395658137;6293.496520278458;6311.779087731908;6332.493400528039;5978.282606087298;6057.606255593372;6059.454649081604;6080.665563394055;6117.796956032597;6183.628982261079;6237.96881015592;6266.655333956493;6269.843253918689;6270.404311590391;5967.7911273106965;6050.122732203894;6071.787487438095;6076.605020673642;6095.75616975316;5845.240118784701;5905;5921.052631578927;5968.552358573038;6068.969655151696;5869.178315035983;5916.7041647918295;5957.463828255478;5971;5972.25346831646;6048.4858787003595;6058.697065146637;6061.909071363845;6066.636001839904;6094.481969032682;5837.416258374129;5871.266091738533;5884.852878678067;5927.940720592739;5932.79235226765 16:20:48 INFO - PROCESS | 1665 | |1;ext.html;460.8801711554997;494.22900275570134;511.4987312433815;512.3825789923143;527.0250037430751;30987.535186972214;40879.77360679181;42138.578614213846;42260.57739422605;44340.3348949766;42641.933951651146;43100.70697879069;43104.568954310445;43175.70472885818;43350.78324608372;9620.999999999982;9810;9817.754556136082;9821.066998635119;9871.210303175729;8682.1317868213;8940.597673104727;11904.7619047619;12185.32980686062;12202.267863928148;11895.881041189607;12033.097517686165;12105.818412723816;12125.272483650964;12143.210691305081;12088.637340879744;12148.514059437612;12161.966232870216;12166.148369614131;12166.330851803148;8600.397972141953;8886.377953543255;11478;11828.408579570987;11848.17062805604;41785;42144.1571168578;42231.36652950178;42301.1539769206;42384;3240.5949256342956;3306.5205545196054;3333.233356328034;3338.432466480704;3368.1411240133853;31170.376592468023;31526.684733152604;31637.367252655047;31885.840570796998;31945.840270798497;3098.6448407364105;3181.984090079535;3209.3420848725955;3248.090534650302;3288.32589319189;399081;438237.8088109539;463217;480505;482331;50611.74694126506;52367.47632523626;59800;60466;61096.77806443846;60345.09482357768;63908;63943.680281598296;64004;64046;15789.28948197334;15918.92040539813;15942.36230550765;15944.883858130052;16002.559449718134;11010.119190464744;11263.32420054783;12139;12239.510419583117;12256.632301030804;14041.438342466186;16879.493615191564;17083.316667333416;17253.757011588412;17428.302867885395;6085.634861908211;6102.420270074336;6109.236345456818;6186.133941248228;6215.81352559424;844.1684940333766;862.2196911794836;968.7142293023575;968.7239136845965;1028.6091285311684;23267.767322326898;29268.560971585444;29499.262518436783;29602.411626197973;29967.550486742715;809.8947136872287;948.9087549318201;1006.9798604027878;1008.8234558952154;1119.8040342939953;13094.607161785161;14800.555983320517;15431.074135551902;15724.68550628981;15726.29231684577;627549;631140;631602;631844;632323;58397;58468.415315846294;58597.414025859194;58652.70673646605;58987.52531186755;1233.1552886272932;1275.1392809853126;1352.2359866675297;1376.5319791271015;1387.4103506009906;29817.25456863596;31134.68865311318;31207.84396078005;31213.375732485678;31875.20311992219;49963;50634.49365506445;50655.24017139672;50694.74652626713;50884.491155089454;430619;509333;510143;510839;511584;25936.61095083613;26786.19641410761;26824.195274141806;27519.17442476729;27563.724362756115;46663.53336466592;46992.060158797314;47381.763091185705;47454.76272618615;48466.5153348462;39003.41494877522;39230.41154382744;39246.607533924296;39774.20451590894;40625.18749624932;38838.41742373923;38987.80506097451;38996.415053774785;40187.59812401838;40523.59476405316;29719.851400743722;38171.23657526889;38540.0278689265;38746.22507549889;38843.590807517576;25427.74572254254;33929.66070339265;34044;34496.655033449344;34812.82593587016;20374.898125509277;24822.50354992926;25295.873520632278;25452.363690907874;25470.49059018772 16:20:48 INFO - PROCESS | 1665 | |2;jquery.html;325.72965434804274;360.76009453713283;374.4346037483398;379.6981399787168;399.9300122478567;14937.850621493782;15142.16718080505;15841.12873791941;20182.899085504552;20615.175392984263;19176.808231917676;19578.314758983437;20195.495112622193;20261.696074558895;20462.795372046276;5906.704664766766;5983.923786841317;6089.512838972893;6110.9694451527685;6227.937720622781;4704.623630109583;5316.548093412045;6088.1476593276975;6099.817005489843;6193.628382297076;5921.230240068785;6038.426349496791;6127.1728316677345;6128.4178003089855;6133.2026836511195;5965.791197308095;6090.360512146205;6098.847528811771;6107.633541987495;6111.419415155553;5119.283300337956;5261.158214685632;5273.973630131824;5399.730013499309;5463.754131064072;12966.611001669871;13226.272555009567;13239.20564766117;15136.199803036474;25087.12195073164;2166.9783302166934;2185.4317877351846;2192.451887028243;2216.5899308627795;2237.3064350051536;9691.660791872251;9707.61169553217;10367.740806479898;11021.559137634486;15652.608684782857;1774.6184570317314;1776.8045514993341;1778.2353587957173;1799.9010054447;1818.3544841581313;90533.09466905313;96736.54895176578;102974;109480.4525977373;117578;10994.395308257963;11257.662270131908;11513.30920144794;11562.190646654823;11702.941485292518;14301.856981430261;14310;14475.276236188149;14509.056911300731;14528.927355363156;8218.63016164274;8327.375446841512;8380.664773409117;8517.318614510825;8538.743837684879;8235.258826705625;8383.413161078666;8400.705975290957;8598.78503037429;8599.441036332619;3880.78655673935;4386.407834942306;4457.398251236106;4594.541920072812;4629.837955671535;1889.782674992366;1910.9044547772555;1917.6931690929384;1918.4915997260737;1920.6542822292122;769.8383339498656;855.4653341661461;878.85938249881;899.9965038981619;952.4666186935339;20510.589788204452;21471.57056858854;21487.67768483474;21641.61345337745;21773;808.1837344282317;816.808050108224;874.6501399440275;912.7124955638952;999.3254553176577;9735.805283894424;9995.800503939574;11385.544578216945;11391.544338226378;11404.201705880674;524728;576619;578021;607728;610511;107501.92498075076;123886;125096;125909.74090259163;139746.301268493;947.8388673925449;986.7187851462298;1014.4116412480892;1016.3901659004538;1019.2712210769188;19138.904305478383;19718.8028119717;19720.112594932976;19725.506862328555;19760.80239197647;54349.18476222781;54578;55420;56229.31312060481;56381;81511.36977260541;81727;82118;82229.76655350055;82786;6664.233613134552;7792.688292468238;7936.127026027017;7960.442769006114;7995.880061798961;7494.775156745306;9087.727368178965;12563.309018004098;12880.35598220104;13079.411426486022;10716.517756701123;10986.066184374418;11011.559537618405;11067.50196241155;11161.72095697614;10777.784444310913;10846.13230941507;10880.347179168959;10958.342499450058;11019.504122314354;9855.507224638883;10376.806667233364;10867.945660271647;11091.279066860629;11223.775524489301;6896;7293;7316.634168291671;7460.216677248811;8158;9622.37454565451;11100.222984391015;11284.717882052686;11458.484368203617;11480.483378248166 16:20:48 INFO - PROCESS | 1665 | |3;mootools.html;1337.5786627212422;1496.947606833761;1511.2217208137808;1687.173285090306;1870.6632806094906;7907.090684571276;8355.039170495395;8644.567771611419;8922.77693057674;8948.373613847034;8795.472271663704;8812.691555795549;8829.470231786097;8831.735047948572;8883.822323553524;2347.6595893595418;2366.739658637557;2375.5367703297866;2379.9048038078545;2384.5707772600927;2340.2277248507953;2367.4081479630095;2387.140629373424;2389.569877422063;2394.988025059881;2343.6055546949624;2369.8222633302485;2373.1575290771784;2376.7742064503846;2380.3454050136265;2073.776471881597;2329.79031887131;2352.4118970257437;2364.2670772060715;2368.3723813189517;2252.560750653623;2349.917752878658;2358.2689366296504;2358.6344116661944;2360.1975328388257;7423.851522969564;8775.25410340122;8833.911660883374;8842.778930526723;8852;4837.201861692816;5024.296598476216;5122.436531981479;5357.7589008494715;5435.537979271727;3370.8483118259737;3610.62088480711;3723.478712980185;3726.217494326195;3740.738148329618;946.9100435458621;1069.786042791437;1096.7751610919743;1122.2368789223324;1125.0999200639455;11637.010854077414;11639.952404283655;11682;11688.41557922109;11780.352080635475;4352.73883566987;4665.533446655308;4689;4886.804527818918;4943.8516844494725;8772.122787721177;9269;9270.397424167408;9282.628694852265;9287.071292870658;5291.576673866081;5312.335958005249;5316.441773613715;5318.813841515528;5323.0950738373795;4758.191107511731;4924.7537623119415;4980;4983.302337672729;4990.10178167926;3902.2780785554764;3922.646961773454;3964.524257089168;4052.675785937118;4060.7766572838186;1260.4580030586935;1262.9431675574622;1268.460904115747;1276.4511260158035;1279.3475327583;802.3019972623738;864.6325311742485;895.4135041547796;928.8281667891462;945.4232917920064;5752.165935939265;5869.559783016291;5926.881462370815;6071.267929431773;6187.288461826947;938.4134915677702;956.8038552096804;961.8268711631973;964.6382606522554;995.6714284286168;3815.8855234343014;3922.274379239793;3933.0167458135465;4088.7546747195265;4096.774677392712;10002.449865257335;10377.27359084872;10379.273450858518;10456.215783816093;10948.178886583539;10673.733156671145;10797.676069717922;10797.892021079846;10870.456477176109;10871.29336593119;7848.372130229568;8196.795080122925;8313.958430207811;8331.708390206313;8332.33341332692;5855.94144058554;5880.588358814842;6021.277446706424;6061.848453788691;6092.817215483543;9917.504124793588;10134.9865013501;10145.137663298407;10155;10155.238357123097;7846.725364612211;10169.644062457779;10187.847182292124;10262.897371026493;10269.024442677934;7471.626418679153;7625.961870190614;7627.54234745917;7627.809304767426;7636.08366995964;8174.591270436335;8241.217084376975;8320.958395208227;8326.666933322842;8330.541820199947;7488.475806693479;7572.356349710336;7595.848083038419;7620.390368770596;7634.122075961335;7315.049043624294;7333.6333183341685;7362.889556656547;7440.62796860144;7509.774706758806;6897.827554310981;6918.169819621678;6978.546394484342;6980.895286570807;6982.9301706984315;4105.281575724296;4958.42978057528;4966.35437393151;4987.451380348231;5163.096458119739;4895.241237608177;4964.925526117039;5013.047520971004;5096.872578185575;5206.297149884868 16:20:48 INFO - PROCESS | 1665 | |4;prototype.html;260.39198471568886;306.6351042259711;308.80699562773276;313.20133659169096;318.18861902149513;4109.794510274482;4121.691852562567;4563.885902852427;5154.948450515493;5290.629655924082;4440.844570440036;4882.779305173706;5237.538356972226;5286.31277933868;5320.574354051676;3681.44778283257;3861.691064714823;3978.8010599470053;4007.158496715693;4111.814968326433;3267.5425440438357;4053.8581149659767;4076.30702780528;4076.388541718736;4123.422720819088;3288.983555082233;3696.355121970724;4001.5998400159897;4020.8488458674615;4035.639151096612;2950.2329394357407;3655.890323290279;3911.863084792047;4022.718409711336;4027.41602467642;3059.990203232928;3506.8597256109724;3509.263054758478;3517.8944631660834;3525.5416795816636;4144.357624568167;4342.131573685244;4798.880027999293;5123.205903085028;5150.201718733602;4490.516319884641;4633;4637.072585482917;4647.697899636547;4683.976580117112;5486.478784515464;5516.420775818561;5521.751521181556;5583.832485025456;5593.860153496154;3227.80633162011;3878.45701601776;3884.4950156479476;3901.200253947962;3935.5670876203594;5579.888402231933;5831.854203644901;5844.736986835603;5845.298564172284;5892;3263.6083669959758;3417.547542294515;3865.7100717446306;3936.547297060875;3938.7592908233814;3985.860494882665;3994.2211268802885;4001.239764444746;4022;4051.47330846988;4604.631629469634;4609.677322587387;4611.5619016193405;4648.48866624671;4659.767011649472;4884.438289596671;5368.677879327252;5608.251956541266;5624.521915637217;5636.943630563642;1345.2264947654942;1365.8458602480887;1367.4871923028863;1373.979390309146;1375.8624137586362;711.4095300900241;789.2935822438885;789.8973133492609;838.8867502887155;853.9829203415896;4424.3142312941545;4807.163773373726;4936.310505020245;5026.371703537058;5132.384113906281;4884.780184891689;5147.871303217449;5651.547876169897;5655.4344565543115;5684.333604824865;5038.294638750578;5137.743112844418;5280;5318.494742999409;5343.625946183807;2844.189406019316;2869.297022229567;2987.3726517431146;3044.5737596736476;3046.6191726034244;4946.579540739005;5959.433853783884;5961.374055724086;5971.193888824953;5972.701364931823;5664.26364572603;5880.794172203952;5893.970530147408;5897.616654917417;5960.731767070492;4979.526944940225;5886.6173698709445;5895.94104058954;5973.761049557969;6036.667983260875;5561.944380556304;5607.632501900704;5730.656160630376;5754;5794.884102317846;4545.749983750926;4925.84745762705;5409.513143817076;5674.858128546654;5705.315362156568;5885.529157667462;5947.286325640778;5974.701264936823;5978.638437817125;5985.6408615483215;3805.83067651436;3923.625681913495;3940.3695408734466;3959.6436320731273;3992.3213053781506;4839.588634966068;5350.384705758887;5681.801136960186;5712.457316554921;5746.195532625352;4853.684510506806;5482.230459500261;5528.9723551381985;5574.721263936868;5613.24221230145;5104.3619547556555;5144.551957905447;5224.582033437392;5537.418571050143;5586.217929489956;4336.9132617348105;4868.245421959602;4886.828960986349;5215.982883337712;5437.238786569963;4633.467151277646;5285.656432331886;5386.649867758583;5450.264214331015;5489.945100549103;5121.615878809179;5447.9104179165115;5448.746788238648;5464.480874316933;5476.534494567847 16:20:48 INFO - PROCESS | 1665 | |5;yui.html;697.8813601687714;712.3375261007261;759.4569882533987;797.2983774278636;805.4885147931062;15675.216239188037;15929.681406371865;15946.920265398685;15948.441804536844;15979.840201597981;14379.06536075157;14453.855461445382;14454.205018723966;14491.855081449183;14507.419703211859;674.4469534981317;693.3932808792307;695.1901035293877;832.629479881453;836.8661014237723;864.1013346120028;864.6714248585561;870.4690139015228;871.3508436214997;873.1704880363649;920.4523308631353;921.9769505762342;922.3773952582014;923.6028507741669;923.7782932096316;914.9908500914972;915.4827522449795;915.5834095486571;917.0096296000304;923.5428462910869;518.390890703422;524.1142469227033;539.5225225675266;539.5440852479649;682.7405585877335;12451.315177665225;14251.002429829916;14747.483838065724;14790.18653974031;15089.698206035928;14294.142351458946;14864.479743208936;14872.479463218735;15065.397384104623;15093.849061509354;10380.844287335696;11788.528458861636;11892.286462812172;11986.400679966055;12043.397830108459;3862.439946207784;3864.111254411473;3955.545112312092;3964.8414063437426;4054.493188351456;101399.49300253525;102518;102724.48637756838;110672;111058.44470777594;11316.717082073013;11338.149638777124;11339.536682216502;11421.42892855354;11700.473478693479;22983.885080574826;23216;23263.53472930532;23326.65010024851;23938.52122957531;8039.35685145187;8156.388270879707;8173.223543763333;8246.505209687439;8420.578971051424;4183.748975061506;4275.615194632498;4293.785310734451;4325.091730736581;4395.84614538496;3544;3609.242059167604;3666.6699997000396;3671.1372827385544;3679.944800827936;656.3698849104817;658.5949640970769;667.8463953290673;681.2982627893277;683.9145106861642;501.3707796715172;542.4873494547614;568.3151802078568;650.9479241660656;696.195893742728;8523.531205783618;9343.205827504738;9499.810003799885;9506.239500839916;9555.665551705657;611.1413464082971;645.9289478157399;672.2672287206871;673.6598018000878;688.3907741648577;4611.953880461219;4638.350630911675;4656.348111264426;4658.231391820312;4677.375877320014;118449;143109;144618;145689.27155364156;147351;126055.94627885811;127521;130006;131236.3438182822;133496;70712.64643676749;72489.0393085135;72508;94320;96292;9488.383255088396;9841.507924603884;9867.654632087842;9879.20966322678;9920.107190352903;61089.669239691524;61179.38820611737;61333.38666613277;62330.688346557974;62746.68626656837;82174;88227;89410.10589894018;90590.54704726698;92058.07941920495;11013.614523491638;11878.752730963124;12873.871261287268;13195.472181112647;13732.86267137316;14339.856601433852;14402.3541734207;14670.926645366704;14840.332185051482;14886.10683359002;13609.727805444034;13945.233012184428;13968.37142328618;13991.510297139552;14277.500287490304;12544.623661290176;12785.87214127884;13477.19136851792;13506;13926.164430134224;12160.878391215974;12331.383430828602;12350.320732359807;12399.13206075566;12800.10399272042;10765.439011343467;11140.498677559368;11365.147613928822;11544.249623774427;11801.583809942862;10532.368057916243;10720.571177152826;11349.54601815918;11455.541778328774;11662.88337116618 16:20:48 INFO - PROCESS | 1665 | __end_tp_report 16:20:48 INFO - PROCESS | 1665 | __start_cc_report 16:20:48 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1456 16:20:48 INFO - PROCESS | 1665 | __end_cc_report 16:20:48 INFO - PROCESS | 1665 | __startTimestamp1475364048638__endTimestamp 16:20:48 INFO - PROCESS | 1665 | 16:20:48 INFO - PROCESS | 1665 | ------- Summary: start ------- 16:20:48 INFO - PROCESS | 1665 | Number of tests: 6 16:20:48 INFO - PROCESS | 1665 | 16:20:48 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5687.45 Median:5916.70 stddev:682.79 (11.5%) stddev-sans-first:658.37 16:20:48 INFO - PROCESS | 1665 | Values: 3285.6 3287.1 3311.5 3355.4 3372.7 5568.6 5609.6 5677.4 5722.6 5733.6 5658.0 5668.5 5770.6 5778.7 5809.4 4181.3 4264.2 4283.7 5186.4 5225.1 4657.3 5072.1 5257.4 5262.8 5284.4 5064.6 5222.8 5238.3 5258.6 5341.5 5110.3 5147.6 5151.0 5160.1 5173.6 5070.2 5117.9 5129.3 5147.2 5152.8 4695.5 5627.5 5653.0 5663.4 5673.2 6097.9 6120.0 6144.6 6146.5 6188.5 6167.6 6190.8 6252.0 6307.8 6319.1 5735.4 5753.4 5797.7 5811.6 5812.9 6169.0 6239.8 6262.7 6279.2 6337.8 6145.5 6147.5 6169.3 6175.0 6185.5 6268.8 6279.8 6281.8 6298.4 6380.3 5909.8 6026.8 6112.2 6124.8 6205.8 5963.9 5993.0 6007.7 6015.5 6062.8 4754.4 4757.8 4759.4 4767.6 4836.1 4250.2 4251.3 4269.0 4397.2 4444.5 5700.3 5744.4 5757.5 5765.4 5834.4 6224.4 6265.6 6275.2 6315.3 6316.7 5774.8 5783.5 5788.8 5813.5 5831.2 5273.5 5277.6 5308.4 5359.2 5371.1 6229.3 6257.6 6260.9 6289.1 6305.7 6223.9 6290.6 6299.3 6305.2 6315.7 6223.0 6286.8 6293.5 6311.8 6332.5 5978.3 6057.6 6059.5 6080.7 6117.8 6183.6 6238.0 6266.7 6269.8 6270.4 5967.8 6050.1 6071.8 6076.6 6095.8 5845.2 5905.0 5921.1 5968.6 6069.0 5869.2 5916.7 5957.5 5971.0 5972.3 6048.5 6058.7 6061.9 6066.6 6094.5 5837.4 5871.3 5884.9 5927.9 5932.8 16:20:48 INFO - PROCESS | 1665 | 16:20:48 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:66713.22 Median:38841.00 stddev:141937.76 (365.4%) stddev-sans-first:142248.77 16:20:48 INFO - PROCESS | 1665 | Values: 460.9 494.2 511.5 512.4 527.0 30987.5 40879.8 42138.6 42260.6 44340.3 42641.9 43100.7 43104.6 43175.7 43350.8 9621.0 9810.0 9817.8 9821.1 9871.2 8682.1 8940.6 11904.8 12185.3 12202.3 11895.9 12033.1 12105.8 12125.3 12143.2 12088.6 12148.5 12162.0 12166.1 12166.3 8600.4 8886.4 11478.0 11828.4 11848.2 41785.0 42144.2 42231.4 42301.2 42384.0 3240.6 3306.5 3333.2 3338.4 3368.1 31170.4 31526.7 31637.4 31885.8 31945.8 3098.6 3182.0 3209.3 3248.1 3288.3 399081.0 438237.8 463217.0 480505.0 482331.0 50611.7 52367.5 59800.0 60466.0 61096.8 60345.1 63908.0 63943.7 64004.0 64046.0 15789.3 15918.9 15942.4 15944.9 16002.6 11010.1 11263.3 12139.0 12239.5 12256.6 14041.4 16879.5 17083.3 17253.8 17428.3 6085.6 6102.4 6109.2 6186.1 6215.8 844.2 862.2 968.7 968.7 1028.6 23267.8 29268.6 29499.3 29602.4 29967.6 809.9 948.9 1007.0 1008.8 1119.8 13094.6 14800.6 15431.1 15724.7 15726.3 627549.0 631140.0 631602.0 631844.0 632323.0 58397.0 58468.4 58597.4 58652.7 58987.5 1233.2 1275.1 1352.2 1376.5 1387.4 29817.3 31134.7 31207.8 31213.4 31875.2 49963.0 50634.5 50655.2 50694.7 50884.5 430619.0 509333.0 510143.0 510839.0 511584.0 25936.6 26786.2 26824.2 27519.2 27563.7 46663.5 46992.1 47381.8 47454.8 48466.5 39003.4 39230.4 39246.6 39774.2 40625.2 38838.4 38987.8 38996.4 40187.6 40523.6 29719.9 38171.2 38540.0 38746.2 38843.6 25427.7 33929.7 34044.0 34496.7 34812.8 20374.9 24822.5 25295.9 25452.4 25470.5 16:20:48 INFO - PROCESS | 1665 | 16:20:48 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:33780.34 Median:377.07 stddev:96788.89 (25668.9%) stddev-sans-first:97027.81 16:20:48 INFO - PROCESS | 1665 | Values: 325.7 360.8 374.4 379.7 399.9 14937.9 15142.2 15841.1 20182.9 20615.2 19176.8 19578.3 20195.5 20261.7 20462.8 5906.7 5983.9 6089.5 6111.0 6227.9 4704.6 5316.5 6088.1 6099.8 6193.6 5921.2 6038.4 6127.2 6128.4 6133.2 5965.8 6090.4 6098.8 6107.6 6111.4 5119.3 5261.2 5274.0 5399.7 5463.8 12966.6 13226.3 13239.2 15136.2 25087.1 2167.0 2185.4 2192.5 2216.6 2237.3 9691.7 9707.6 10367.7 11021.6 15652.6 1774.6 1776.8 1778.2 1799.9 1818.4 90533.1 96736.5 102974.0 109480.5 117578.0 10994.4 11257.7 11513.3 11562.2 11702.9 14301.9 14310.0 14475.3 14509.1 14528.9 8218.6 8327.4 8380.7 8517.3 8538.7 8235.3 8383.4 8400.7 8598.8 8599.4 3880.8 4386.4 4457.4 4594.5 4629.8 1889.8 1910.9 1917.7 1918.5 1920.7 769.8 855.5 878.9 900.0 952.5 20510.6 21471.6 21487.7 21641.6 21773.0 808.2 816.8 874.7 912.7 999.3 9735.8 9995.8 11385.5 11391.5 11404.2 524728.0 576619.0 578021.0 607728.0 610511.0 107501.9 123886.0 125096.0 125909.7 139746.3 947.8 986.7 1014.4 1016.4 1019.3 19138.9 19718.8 19720.1 19725.5 19760.8 54349.2 54578.0 55420.0 56229.3 56381.0 81511.4 81727.0 82118.0 82229.8 82786.0 6664.2 7792.7 7936.1 7960.4 7995.9 7494.8 9087.7 12563.3 12880.4 13079.4 10716.5 10986.1 11011.6 11067.5 11161.7 10777.8 10846.1 10880.3 10958.3 11019.5 9855.5 10376.8 10867.9 11091.3 11223.8 6896.0 7293.0 7316.6 7460.2 8158.0 9622.4 11100.2 11284.7 11458.5 11480.5 16:20:48 INFO - PROCESS | 1665 | 16:20:48 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5691.99 Median:4981.65 stddev:3161.84 (63.5%) stddev-sans-first:3153.77 16:20:48 INFO - PROCESS | 1665 | Values: 1337.6 1496.9 1511.2 1687.2 1870.7 7907.1 8355.0 8644.6 8922.8 8948.4 8795.5 8812.7 8829.5 8831.7 8883.8 2347.7 2366.7 2375.5 2379.9 2384.6 2340.2 2367.4 2387.1 2389.6 2395.0 2343.6 2369.8 2373.2 2376.8 2380.3 2073.8 2329.8 2352.4 2364.3 2368.4 2252.6 2349.9 2358.3 2358.6 2360.2 7423.9 8775.3 8833.9 8842.8 8852.0 4837.2 5024.3 5122.4 5357.8 5435.5 3370.8 3610.6 3723.5 3726.2 3740.7 946.9 1069.8 1096.8 1122.2 1125.1 11637.0 11640.0 11682.0 11688.4 11780.4 4352.7 4665.5 4689.0 4886.8 4943.9 8772.1 9269.0 9270.4 9282.6 9287.1 5291.6 5312.3 5316.4 5318.8 5323.1 4758.2 4924.8 4980.0 4983.3 4990.1 3902.3 3922.6 3964.5 4052.7 4060.8 1260.5 1262.9 1268.5 1276.5 1279.3 802.3 864.6 895.4 928.8 945.4 5752.2 5869.6 5926.9 6071.3 6187.3 938.4 956.8 961.8 964.6 995.7 3815.9 3922.3 3933.0 4088.8 4096.8 10002.4 10377.3 10379.3 10456.2 10948.2 10673.7 10797.7 10797.9 10870.5 10871.3 7848.4 8196.8 8314.0 8331.7 8332.3 5855.9 5880.6 6021.3 6061.8 6092.8 9917.5 10135.0 10145.1 10155.0 10155.2 7846.7 10169.6 10187.8 10262.9 10269.0 7471.6 7626.0 7627.5 7627.8 7636.1 8174.6 8241.2 8321.0 8326.7 8330.5 7488.5 7572.4 7595.8 7620.4 7634.1 7315.0 7333.6 7362.9 7440.6 7509.8 6897.8 6918.2 6978.5 6980.9 6982.9 4105.3 4958.4 4966.4 4987.5 5163.1 4895.2 4964.9 5013.0 5096.9 5206.3 16:20:48 INFO - PROCESS | 1665 | 16:20:48 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4487.68 Median:4941.45 stddev:1390.73 (28.1%) stddev-sans-first:1357.96 16:20:48 INFO - PROCESS | 1665 | Values: 260.4 306.6 308.8 313.2 318.2 4109.8 4121.7 4563.9 5154.9 5290.6 4440.8 4882.8 5237.5 5286.3 5320.6 3681.4 3861.7 3978.8 4007.2 4111.8 3267.5 4053.9 4076.3 4076.4 4123.4 3289.0 3696.4 4001.6 4020.8 4035.6 2950.2 3655.9 3911.9 4022.7 4027.4 3060.0 3506.9 3509.3 3517.9 3525.5 4144.4 4342.1 4798.9 5123.2 5150.2 4490.5 4633.0 4637.1 4647.7 4684.0 5486.5 5516.4 5521.8 5583.8 5593.9 3227.8 3878.5 3884.5 3901.2 3935.6 5579.9 5831.9 5844.7 5845.3 5892.0 3263.6 3417.5 3865.7 3936.5 3938.8 3985.9 3994.2 4001.2 4022.0 4051.5 4604.6 4609.7 4611.6 4648.5 4659.8 4884.4 5368.7 5608.3 5624.5 5636.9 1345.2 1365.8 1367.5 1374.0 1375.9 711.4 789.3 789.9 838.9 854.0 4424.3 4807.2 4936.3 5026.4 5132.4 4884.8 5147.9 5651.5 5655.4 5684.3 5038.3 5137.7 5280.0 5318.5 5343.6 2844.2 2869.3 2987.4 3044.6 3046.6 4946.6 5959.4 5961.4 5971.2 5972.7 5664.3 5880.8 5894.0 5897.6 5960.7 4979.5 5886.6 5895.9 5973.8 6036.7 5561.9 5607.6 5730.7 5754.0 5794.9 4545.7 4925.8 5409.5 5674.9 5705.3 5885.5 5947.3 5974.7 5978.6 5985.6 3805.8 3923.6 3940.4 3959.6 3992.3 4839.6 5350.4 5681.8 5712.5 5746.2 4853.7 5482.2 5529.0 5574.7 5613.2 5104.4 5144.6 5224.6 5537.4 5586.2 4336.9 4868.2 4886.8 5216.0 5437.2 4633.5 5285.7 5386.6 5450.3 5489.9 5121.6 5447.9 5448.7 5464.5 5476.5 16:20:48 INFO - PROCESS | 1665 | 16:20:48 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:23674.08 Median:3863.28 stddev:37019.42 (958.2%) stddev-sans-first:37083.07 16:20:48 INFO - PROCESS | 1665 | Values: 697.9 712.3 759.5 797.3 805.5 15675.2 15929.7 15946.9 15948.4 15979.8 14379.1 14453.9 14454.2 14491.9 14507.4 674.4 693.4 695.2 832.6 836.9 864.1 864.7 870.5 871.4 873.2 920.5 922.0 922.4 923.6 923.8 915.0 915.5 915.6 917.0 923.5 518.4 524.1 539.5 539.5 682.7 12451.3 14251.0 14747.5 14790.2 15089.7 14294.1 14864.5 14872.5 15065.4 15093.8 10380.8 11788.5 11892.3 11986.4 12043.4 3862.4 3864.1 3955.5 3964.8 4054.5 101399.5 102518.0 102724.5 110672.0 111058.4 11316.7 11338.1 11339.5 11421.4 11700.5 22983.9 23216.0 23263.5 23326.7 23938.5 8039.4 8156.4 8173.2 8246.5 8420.6 4183.7 4275.6 4293.8 4325.1 4395.8 3544.0 3609.2 3666.7 3671.1 3679.9 656.4 658.6 667.8 681.3 683.9 501.4 542.5 568.3 650.9 696.2 8523.5 9343.2 9499.8 9506.2 9555.7 611.1 645.9 672.3 673.7 688.4 4612.0 4638.4 4656.3 4658.2 4677.4 118449.0 143109.0 144618.0 145689.3 147351.0 126055.9 127521.0 130006.0 131236.3 133496.0 70712.6 72489.0 72508.0 94320.0 96292.0 9488.4 9841.5 9867.7 9879.2 9920.1 61089.7 61179.4 61333.4 62330.7 62746.7 82174.0 88227.0 89410.1 90590.5 92058.1 11013.6 11878.8 12873.9 13195.5 13732.9 14339.9 14402.4 14670.9 14840.3 14886.1 13609.7 13945.2 13968.4 13991.5 14277.5 12544.6 12785.9 13477.2 13506.0 13926.2 12160.9 12331.4 12350.3 12399.1 12800.1 10765.4 11140.5 11365.1 11544.2 11801.6 10532.4 10720.6 11349.5 11455.5 11662.9 16:20:48 INFO - PROCESS | 1665 | -------- Summary: end -------- 16:20:48 INFO - PROCESS | 1665 | 16:20:49 INFO - TEST-INFO | 1665: exit 0 16:20:49 INFO - TEST-OK | dromaeo_css | took 1119618ms 16:20:49 INFO - TEST-START | kraken 16:20:49 INFO - operating with platform_type : mac_ 16:20:49 INFO - Initialising browser for kraken test... 16:20:49 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmpsv8LOa/profile http://localhost:49274/getInfo.html) 16:20:51 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 16:20:51 INFO - PROCESS | 1671 | colorDepth:24 16:20:51 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 16:20:51 INFO - PROCESS | 1671 | __metrics 16:20:51 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:20:51 INFO - PROCESS | 1671 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:20:52 INFO - TEST-INFO | 1671: exit 0 16:20:52 INFO - Browser initialized. 16:20:52 INFO - Running cycle 1/1 for kraken test... 16:20:52 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmpsv8LOa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:21:04 INFO - PROCESS | 1672 | RSS: Main: 235520000 16:21:04 INFO - PROCESS | 1672 | 16:21:07 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 16:21:08 INFO - PROCESS | 1672 | RSS: Main: 294596608 16:21:08 INFO - PROCESS | 1672 | 16:21:10 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 16:21:11 INFO - PROCESS | 1672 | RSS: Main: 293916672 16:21:11 INFO - PROCESS | 1672 | 16:21:14 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 16:21:15 INFO - PROCESS | 1672 | RSS: Main: 383250432 16:21:15 INFO - PROCESS | 1672 | 16:21:17 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 16:21:18 INFO - PROCESS | 1672 | RSS: Main: 285863936 16:21:18 INFO - PROCESS | 1672 | 16:21:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:21:20 INFO - PROCESS | 1672 | RSS: Main: 290344960 16:21:20 INFO - PROCESS | 1672 | 16:21:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 16:21:24 INFO - PROCESS | 1672 | RSS: Main: 327860224 16:21:24 INFO - PROCESS | 1672 | 16:21:27 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 16:21:27 INFO - PROCESS | 1672 | RSS: Main: 328216576 16:21:27 INFO - PROCESS | 1672 | 16:21:30 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 16:21:31 INFO - PROCESS | 1672 | RSS: Main: 327163904 16:21:31 INFO - PROCESS | 1672 | 16:21:32 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:21:33 INFO - PROCESS | 1672 | RSS: Main: 303812608 16:21:33 INFO - PROCESS | 1672 | 16:21:35 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:21:36 INFO - PROCESS | 1672 | RSS: Main: 305999872 16:21:36 INFO - PROCESS | 1672 | 16:21:38 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:21:38 INFO - PROCESS | 1672 | RSS: Main: 313384960 16:21:38 INFO - PROCESS | 1672 | 16:21:41 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:21:42 INFO - PROCESS | 1672 | RSS: Main: 317095936 16:21:42 INFO - PROCESS | 1672 | 16:21:45 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:21:46 INFO - PROCESS | 1672 | RSS: Main: 318902272 16:21:46 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 16:21:48 INFO - PROCESS | 1672 | RSS: Main: 362352640 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | __start_tp_report 16:21:48 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 16:21:48 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 16:21:48 INFO - PROCESS | 1672 | |i|pagename|runs| 16:21:48 INFO - PROCESS | 1672 | |0;ai-astar;114;115;111;113;114;114;113;112;113;113 16:21:48 INFO - PROCESS | 1672 | |1;audio-beat-detection;151;111;114;150;111;113;113;111;146;114 16:21:48 INFO - PROCESS | 1672 | |2;audio-dft;184;183;185;186;181;186;179;181;183;184 16:21:48 INFO - PROCESS | 1672 | |3;audio-fft;79;57;59;86;63;58;60;59;83;58 16:21:48 INFO - PROCESS | 1672 | |4;audio-oscillator;87;72;92;69;68;69;93;68;68;89 16:21:48 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;88;85;88;85;89;84;91;129;118;85 16:21:48 INFO - PROCESS | 1672 | |6;imaging-darkroom;94;93;92;93;94;93;93;92;95;105 16:21:48 INFO - PROCESS | 1672 | |7;imaging-desaturate;81;80;131;136;113;86;85;85;85;84 16:21:48 INFO - PROCESS | 1672 | |8;json-parse-financial;58;54;56;56;56;57;57;57;57;57 16:21:48 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;66;68;75;74;71;69;70;69;70;71 16:21:48 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;87;89;89;89;89;90;92;89;85;112 16:21:48 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;148;150;143;153;156;157;145;141;159;142 16:21:48 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;171;170;170;157;237;240;177;171;173;171 16:21:48 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;69;69;70;70;69;68;69;75;79;91 16:21:48 INFO - PROCESS | 1672 | __end_tp_report 16:21:48 INFO - PROCESS | 1672 | __start_cc_report 16:21:48 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1932 16:21:48 INFO - PROCESS | 1672 | __end_cc_report 16:21:48 INFO - PROCESS | 1672 | __startTimestamp1475364108271__endTimestamp 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | ------- Summary: start ------- 16:21:48 INFO - PROCESS | 1672 | Number of tests: 14 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:113.20 Median:113.50 stddev:1.14 (1.0%) stddev-sans-first:1.17 16:21:48 INFO - PROCESS | 1672 | Values: 114.0 115.0 111.0 113.0 114.0 114.0 113.0 112.0 113.0 113.0 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:123.40 Median:114.00 stddev:17.75 (15.6%) stddev-sans-first:15.76 16:21:48 INFO - PROCESS | 1672 | Values: 151.0 111.0 114.0 150.0 111.0 113.0 113.0 111.0 146.0 114.0 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:2.30 (1.2%) stddev-sans-first:2.42 16:21:48 INFO - PROCESS | 1672 | Values: 184.0 183.0 185.0 186.0 181.0 186.0 179.0 181.0 183.0 184.0 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:66.20 Median:61.50 stddev:11.59 (18.9%) stddev-sans-first:11.33 16:21:48 INFO - PROCESS | 1672 | Values: 79.0 57.0 59.0 86.0 63.0 58.0 60.0 59.0 83.0 58.0 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:79.50 stddev:11.15 (14.0%) stddev-sans-first:11.28 16:21:48 INFO - PROCESS | 1672 | Values: 87.0 72.0 92.0 69.0 68.0 69.0 93.0 68.0 68.0 89.0 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:86.50 stddev:15.81 (18.3%) stddev-sans-first:16.61 16:21:48 INFO - PROCESS | 1672 | Values: 88.0 85.0 88.0 85.0 89.0 84.0 91.0 129.0 118.0 85.0 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:93.00 stddev:3.84 (4.1%) stddev-sans-first:4.07 16:21:48 INFO - PROCESS | 1672 | Values: 94.0 93.0 92.0 93.0 94.0 93.0 93.0 92.0 95.0 105.0 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:96.60 Median:84.50 stddev:21.60 (25.6%) stddev-sans-first:22.16 16:21:48 INFO - PROCESS | 1672 | Values: 81.0 80.0 131.0 136.0 113.0 86.0 85.0 85.0 85.0 84.0 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:1.08 (1.9%) stddev-sans-first:1.00 16:21:48 INFO - PROCESS | 1672 | Values: 58.0 54.0 56.0 56.0 56.0 57.0 57.0 57.0 57.0 57.0 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:70.50 stddev:2.67 (3.8%) stddev-sans-first:2.33 16:21:48 INFO - PROCESS | 1672 | Values: 66.0 68.0 75.0 74.0 71.0 69.0 70.0 69.0 70.0 71.0 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:89.00 stddev:7.56 (8.5%) stddev-sans-first:7.88 16:21:48 INFO - PROCESS | 1672 | Values: 87.0 89.0 89.0 89.0 89.0 90.0 92.0 89.0 85.0 112.0 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:149.40 Median:151.50 stddev:6.62 (4.4%) stddev-sans-first:7.00 16:21:48 INFO - PROCESS | 1672 | Values: 148.0 150.0 143.0 153.0 156.0 157.0 145.0 141.0 159.0 142.0 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.70 Median:172.00 stddev:29.33 (17.1%) stddev-sans-first:30.75 16:21:48 INFO - PROCESS | 1672 | Values: 171.0 170.0 170.0 157.0 237.0 240.0 177.0 171.0 173.0 171.0 16:21:48 INFO - PROCESS | 1672 | 16:21:48 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:70.00 stddev:7.23 (10.3%) stddev-sans-first:7.53 16:21:48 INFO - PROCESS | 1672 | Values: 69.0 69.0 70.0 70.0 69.0 68.0 69.0 75.0 79.0 91.0 16:21:48 INFO - PROCESS | 1672 | -------- Summary: end -------- 16:21:48 INFO - PROCESS | 1672 | 16:21:49 INFO - TEST-INFO | 1672: exit 0 16:21:49 INFO - TEST-OK | kraken | took 59885ms 16:21:49 INFO - SUITE-END | took 1179s 16:21:49 INFO - Completed test suite (00:19:39) 16:21:49 INFO - javascript benchmark 16:21:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3285.605727312722, 3287.1124796304985, 3311.4536101543245, 3355.4463513520272, 3372.7133193678537, 5568.582356323272, 5609.607327487072, 5677.403872593381, 5722.570807189457, 5733.598648094629, 5657.9717101414435, 5668.546516278698, 5770.596058275923, 5778.653280803154, 5809.419058094199, 4181.3309870420735, 4264.189803937257, 4283.657307415399, 5186.429492755794, 5225.137852254393, 4657.254839225725, 5072.112380333445, 5257.421683614818, 5262.81580144695, 5284.445133260991, 5064.620153488503, 5222.791088356461, 5238.292830467914, 5258.605604579653, 5341.4658534146665, 5110.310108135392, 5147.562457191143, 5151.021305951857, 5160.071187186305, 5173.586113110942, 5070.163423035195, 5117.948820511784, 5129.281900533928, 5147.22791581262, 5152.819651312186, 4695.5304469552775, 5627.493525582717, 5652.971735141339, 5663.405342439067, 5673.177389278531, 6097.857415703, 6119.958356786701, 6144.586745048664, 6146.4775494082605, 6188.535859810532, 6167.599106058092, 6190.81427557174, 6251.968740156316, 6307.842303942438, 6319.083732858756, 5735.397783232742, 5753.367129615739, 5797.710114494258, 5811.593188476808, 5812.88374232513, 6168.9691551541955, 6239.812805615838, 6262.6868656566985, 6279.246490421178, 6337.841553961188, 6145.525073982212, 6147.508199344042, 6169.321374648785, 6174.9691251544355, 6185.536084793658, 6268.780592679234, 6279.780207692709, 6281.780137695158, 6298.401651843067, 6380.298167201604, 5909.793157239562, 6026.758929642853, 6112.235970503687, 6124.836281106575, 6205.81382558524, 5963.850903727442, 5992.970035149883, 6007.729652165663, 6015.518758499309, 6062.757489700362, 4754.381930349033, 4757.762111894392, 4759.405074365704, 4767.642426818003, 4836.105320515716, 4250.213710463512, 4251.319788833772, 4268.957310426918, 4397.164538737629, 4444.533324000998, 5700.287464066992, 5744.425557444222, 5757.539396848242, 5765.365809760923, 5834.416558344132, 6224.350884863449, 6265.592736472115, 6275.215598050244, 6315.336889626615, 6316.684165791784, 5774.82675519735, 5783.537317014713, 5788.797392091256, 5813.505852002627, 5831.2127862738835, 5273.499017593323, 5277.577793776489, 5308.416074231832, 5359.249705041298, 5371.060064488777, 6229.283632382334, 6257.624542527463, 6260.937390626035, 6289.119523266655, 6305.747770089145, 6223.937760622517, 6290.622562646256, 6299.338569450325, 6305.243370795534, 6315.652639104894, 6223.0, 6286.811395658137, 6293.496520278458, 6311.779087731908, 6332.493400528039, 5978.282606087298, 6057.606255593372, 6059.454649081604, 6080.665563394055, 6117.796956032597, 6183.628982261079, 6237.96881015592, 6266.655333956493, 6269.843253918689, 6270.404311590391, 5967.7911273106965, 6050.122732203894, 6071.787487438095, 6076.605020673642, 6095.75616975316, 5845.240118784701, 5905.0, 5921.052631578927, 5968.552358573038, 6068.969655151696, 5869.178315035983, 5916.7041647918295, 5957.463828255478, 5971.0, 5972.25346831646, 6048.4858787003595, 6058.697065146637, 6061.909071363845, 6066.636001839904, 6094.481969032682, 5837.416258374129, 5871.266091738533, 5884.852878678067, 5927.940720592739, 5932.79235226765], "value": 5640.861314476004, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.8801711554997, 494.22900275570134, 511.4987312433815, 512.3825789923143, 527.0250037430751, 30987.535186972214, 40879.77360679181, 42138.578614213846, 42260.57739422605, 44340.3348949766, 42641.933951651146, 43100.70697879069, 43104.568954310445, 43175.70472885818, 43350.78324608372, 9620.999999999982, 9810.0, 9817.754556136082, 9821.066998635119, 9871.210303175729, 8682.1317868213, 8940.597673104727, 11904.7619047619, 12185.32980686062, 12202.267863928148, 11895.881041189607, 12033.097517686165, 12105.818412723816, 12125.272483650964, 12143.210691305081, 12088.637340879744, 12148.514059437612, 12161.966232870216, 12166.148369614131, 12166.330851803148, 8600.397972141953, 8886.377953543255, 11478.0, 11828.408579570987, 11848.17062805604, 41785.0, 42144.1571168578, 42231.36652950178, 42301.1539769206, 42384.0, 3240.5949256342956, 3306.5205545196054, 3333.233356328034, 3338.432466480704, 3368.1411240133853, 31170.376592468023, 31526.684733152604, 31637.367252655047, 31885.840570796998, 31945.840270798497, 3098.6448407364105, 3181.984090079535, 3209.3420848725955, 3248.090534650302, 3288.32589319189, 399081.0, 438237.8088109539, 463217.0, 480505.0, 482331.0, 50611.74694126506, 52367.47632523626, 59800.0, 60466.0, 61096.77806443846, 60345.09482357768, 63908.0, 63943.680281598296, 64004.0, 64046.0, 15789.28948197334, 15918.92040539813, 15942.36230550765, 15944.883858130052, 16002.559449718134, 11010.119190464744, 11263.32420054783, 12139.0, 12239.510419583117, 12256.632301030804, 14041.438342466186, 16879.493615191564, 17083.316667333416, 17253.757011588412, 17428.302867885395, 6085.634861908211, 6102.420270074336, 6109.236345456818, 6186.133941248228, 6215.81352559424, 844.1684940333766, 862.2196911794836, 968.7142293023575, 968.7239136845965, 1028.6091285311684, 23267.767322326898, 29268.560971585444, 29499.262518436783, 29602.411626197973, 29967.550486742715, 809.8947136872287, 948.9087549318201, 1006.9798604027878, 1008.8234558952154, 1119.8040342939953, 13094.607161785161, 14800.555983320517, 15431.074135551902, 15724.68550628981, 15726.29231684577, 627549.0, 631140.0, 631602.0, 631844.0, 632323.0, 58397.0, 58468.415315846294, 58597.414025859194, 58652.70673646605, 58987.52531186755, 1233.1552886272932, 1275.1392809853126, 1352.2359866675297, 1376.5319791271015, 1387.4103506009906, 29817.25456863596, 31134.68865311318, 31207.84396078005, 31213.375732485678, 31875.20311992219, 49963.0, 50634.49365506445, 50655.24017139672, 50694.74652626713, 50884.491155089454, 430619.0, 509333.0, 510143.0, 510839.0, 511584.0, 25936.61095083613, 26786.19641410761, 26824.195274141806, 27519.17442476729, 27563.724362756115, 46663.53336466592, 46992.060158797314, 47381.763091185705, 47454.76272618615, 48466.5153348462, 39003.41494877522, 39230.41154382744, 39246.607533924296, 39774.20451590894, 40625.18749624932, 38838.41742373923, 38987.80506097451, 38996.415053774785, 40187.59812401838, 40523.59476405316, 29719.851400743722, 38171.23657526889, 38540.0278689265, 38746.22507549889, 38843.590807517576, 25427.74572254254, 33929.66070339265, 34044.0, 34496.655033449344, 34812.82593587016, 20374.898125509277, 24822.50354992926, 25295.873520632278, 25452.363690907874, 25470.49059018772], "value": 19859.404507957443, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.72965434804274, 360.76009453713283, 374.4346037483398, 379.6981399787168, 399.9300122478567, 14937.850621493782, 15142.16718080505, 15841.12873791941, 20182.899085504552, 20615.175392984263, 19176.808231917676, 19578.314758983437, 20195.495112622193, 20261.696074558895, 20462.795372046276, 5906.704664766766, 5983.923786841317, 6089.512838972893, 6110.9694451527685, 6227.937720622781, 4704.623630109583, 5316.548093412045, 6088.1476593276975, 6099.817005489843, 6193.628382297076, 5921.230240068785, 6038.426349496791, 6127.1728316677345, 6128.4178003089855, 6133.2026836511195, 5965.791197308095, 6090.360512146205, 6098.847528811771, 6107.633541987495, 6111.419415155553, 5119.283300337956, 5261.158214685632, 5273.973630131824, 5399.730013499309, 5463.754131064072, 12966.611001669871, 13226.272555009567, 13239.20564766117, 15136.199803036474, 25087.12195073164, 2166.9783302166934, 2185.4317877351846, 2192.451887028243, 2216.5899308627795, 2237.3064350051536, 9691.660791872251, 9707.61169553217, 10367.740806479898, 11021.559137634486, 15652.608684782857, 1774.6184570317314, 1776.8045514993341, 1778.2353587957173, 1799.9010054447, 1818.3544841581313, 90533.09466905313, 96736.54895176578, 102974.0, 109480.4525977373, 117578.0, 10994.395308257963, 11257.662270131908, 11513.30920144794, 11562.190646654823, 11702.941485292518, 14301.856981430261, 14310.0, 14475.276236188149, 14509.056911300731, 14528.927355363156, 8218.63016164274, 8327.375446841512, 8380.664773409117, 8517.318614510825, 8538.743837684879, 8235.258826705625, 8383.413161078666, 8400.705975290957, 8598.78503037429, 8599.441036332619, 3880.78655673935, 4386.407834942306, 4457.398251236106, 4594.541920072812, 4629.837955671535, 1889.782674992366, 1910.9044547772555, 1917.6931690929384, 1918.4915997260737, 1920.6542822292122, 769.8383339498656, 855.4653341661461, 878.85938249881, 899.9965038981619, 952.4666186935339, 20510.589788204452, 21471.57056858854, 21487.67768483474, 21641.61345337745, 21773.0, 808.1837344282317, 816.808050108224, 874.6501399440275, 912.7124955638952, 999.3254553176577, 9735.805283894424, 9995.800503939574, 11385.544578216945, 11391.544338226378, 11404.201705880674, 524728.0, 576619.0, 578021.0, 607728.0, 610511.0, 107501.92498075076, 123886.0, 125096.0, 125909.74090259163, 139746.301268493, 947.8388673925449, 986.7187851462298, 1014.4116412480892, 1016.3901659004538, 1019.2712210769188, 19138.904305478383, 19718.8028119717, 19720.112594932976, 19725.506862328555, 19760.80239197647, 54349.18476222781, 54578.0, 55420.0, 56229.31312060481, 56381.0, 81511.36977260541, 81727.0, 82118.0, 82229.76655350055, 82786.0, 6664.233613134552, 7792.688292468238, 7936.127026027017, 7960.442769006114, 7995.880061798961, 7494.775156745306, 9087.727368178965, 12563.309018004098, 12880.35598220104, 13079.411426486022, 10716.517756701123, 10986.066184374418, 11011.559537618405, 11067.50196241155, 11161.72095697614, 10777.784444310913, 10846.13230941507, 10880.347179168959, 10958.342499450058, 11019.504122314354, 9855.507224638883, 10376.806667233364, 10867.945660271647, 11091.279066860629, 11223.775524489301, 6896.0, 7293.0, 7316.634168291671, 7460.216677248811, 8158.0, 9622.37454565451, 11100.222984391015, 11284.717882052686, 11458.484368203617, 11480.483378248166], "value": 9256.617134201992, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.5786627212422, 1496.947606833761, 1511.2217208137808, 1687.173285090306, 1870.6632806094906, 7907.090684571276, 8355.039170495395, 8644.567771611419, 8922.77693057674, 8948.373613847034, 8795.472271663704, 8812.691555795549, 8829.470231786097, 8831.735047948572, 8883.822323553524, 2347.6595893595418, 2366.739658637557, 2375.5367703297866, 2379.9048038078545, 2384.5707772600927, 2340.2277248507953, 2367.4081479630095, 2387.140629373424, 2389.569877422063, 2394.988025059881, 2343.6055546949624, 2369.8222633302485, 2373.1575290771784, 2376.7742064503846, 2380.3454050136265, 2073.776471881597, 2329.79031887131, 2352.4118970257437, 2364.2670772060715, 2368.3723813189517, 2252.560750653623, 2349.917752878658, 2358.2689366296504, 2358.6344116661944, 2360.1975328388257, 7423.851522969564, 8775.25410340122, 8833.911660883374, 8842.778930526723, 8852.0, 4837.201861692816, 5024.296598476216, 5122.436531981479, 5357.7589008494715, 5435.537979271727, 3370.8483118259737, 3610.62088480711, 3723.478712980185, 3726.217494326195, 3740.738148329618, 946.9100435458621, 1069.786042791437, 1096.7751610919743, 1122.2368789223324, 1125.0999200639455, 11637.010854077414, 11639.952404283655, 11682.0, 11688.41557922109, 11780.352080635475, 4352.73883566987, 4665.533446655308, 4689.0, 4886.804527818918, 4943.8516844494725, 8772.122787721177, 9269.0, 9270.397424167408, 9282.628694852265, 9287.071292870658, 5291.576673866081, 5312.335958005249, 5316.441773613715, 5318.813841515528, 5323.0950738373795, 4758.191107511731, 4924.7537623119415, 4980.0, 4983.302337672729, 4990.10178167926, 3902.2780785554764, 3922.646961773454, 3964.524257089168, 4052.675785937118, 4060.7766572838186, 1260.4580030586935, 1262.9431675574622, 1268.460904115747, 1276.4511260158035, 1279.3475327583, 802.3019972623738, 864.6325311742485, 895.4135041547796, 928.8281667891462, 945.4232917920064, 5752.165935939265, 5869.559783016291, 5926.881462370815, 6071.267929431773, 6187.288461826947, 938.4134915677702, 956.8038552096804, 961.8268711631973, 964.6382606522554, 995.6714284286168, 3815.8855234343014, 3922.274379239793, 3933.0167458135465, 4088.7546747195265, 4096.774677392712, 10002.449865257335, 10377.27359084872, 10379.273450858518, 10456.215783816093, 10948.178886583539, 10673.733156671145, 10797.676069717922, 10797.892021079846, 10870.456477176109, 10871.29336593119, 7848.372130229568, 8196.795080122925, 8313.958430207811, 8331.708390206313, 8332.33341332692, 5855.94144058554, 5880.588358814842, 6021.277446706424, 6061.848453788691, 6092.817215483543, 9917.504124793588, 10134.9865013501, 10145.137663298407, 10155.0, 10155.238357123097, 7846.725364612211, 10169.644062457779, 10187.847182292124, 10262.897371026493, 10269.024442677934, 7471.626418679153, 7625.961870190614, 7627.54234745917, 7627.809304767426, 7636.08366995964, 8174.591270436335, 8241.217084376975, 8320.958395208227, 8326.666933322842, 8330.541820199947, 7488.475806693479, 7572.356349710336, 7595.848083038419, 7620.390368770596, 7634.122075961335, 7315.049043624294, 7333.6333183341685, 7362.889556656547, 7440.62796860144, 7509.774706758806, 6897.827554310981, 6918.169819621678, 6978.546394484342, 6980.895286570807, 6982.9301706984315, 4105.281575724296, 4958.42978057528, 4966.35437393151, 4987.451380348231, 5163.096458119739, 4895.241237608177, 4964.925526117039, 5013.047520971004, 5096.872578185575, 5206.297149884868], "value": 4585.758700406205, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [260.39198471568886, 306.6351042259711, 308.80699562773276, 313.20133659169096, 318.18861902149513, 4109.794510274482, 4121.691852562567, 4563.885902852427, 5154.948450515493, 5290.629655924082, 4440.844570440036, 4882.779305173706, 5237.538356972226, 5286.31277933868, 5320.574354051676, 3681.44778283257, 3861.691064714823, 3978.8010599470053, 4007.158496715693, 4111.814968326433, 3267.5425440438357, 4053.8581149659767, 4076.30702780528, 4076.388541718736, 4123.422720819088, 3288.983555082233, 3696.355121970724, 4001.5998400159897, 4020.8488458674615, 4035.639151096612, 2950.2329394357407, 3655.890323290279, 3911.863084792047, 4022.718409711336, 4027.41602467642, 3059.990203232928, 3506.8597256109724, 3509.263054758478, 3517.8944631660834, 3525.5416795816636, 4144.357624568167, 4342.131573685244, 4798.880027999293, 5123.205903085028, 5150.201718733602, 4490.516319884641, 4633.0, 4637.072585482917, 4647.697899636547, 4683.976580117112, 5486.478784515464, 5516.420775818561, 5521.751521181556, 5583.832485025456, 5593.860153496154, 3227.80633162011, 3878.45701601776, 3884.4950156479476, 3901.200253947962, 3935.5670876203594, 5579.888402231933, 5831.854203644901, 5844.736986835603, 5845.298564172284, 5892.0, 3263.6083669959758, 3417.547542294515, 3865.7100717446306, 3936.547297060875, 3938.7592908233814, 3985.860494882665, 3994.2211268802885, 4001.239764444746, 4022.0, 4051.47330846988, 4604.631629469634, 4609.677322587387, 4611.5619016193405, 4648.48866624671, 4659.767011649472, 4884.438289596671, 5368.677879327252, 5608.251956541266, 5624.521915637217, 5636.943630563642, 1345.2264947654942, 1365.8458602480887, 1367.4871923028863, 1373.979390309146, 1375.8624137586362, 711.4095300900241, 789.2935822438885, 789.8973133492609, 838.8867502887155, 853.9829203415896, 4424.3142312941545, 4807.163773373726, 4936.310505020245, 5026.371703537058, 5132.384113906281, 4884.780184891689, 5147.871303217449, 5651.547876169897, 5655.4344565543115, 5684.333604824865, 5038.294638750578, 5137.743112844418, 5280.0, 5318.494742999409, 5343.625946183807, 2844.189406019316, 2869.297022229567, 2987.3726517431146, 3044.5737596736476, 3046.6191726034244, 4946.579540739005, 5959.433853783884, 5961.374055724086, 5971.193888824953, 5972.701364931823, 5664.26364572603, 5880.794172203952, 5893.970530147408, 5897.616654917417, 5960.731767070492, 4979.526944940225, 5886.6173698709445, 5895.94104058954, 5973.761049557969, 6036.667983260875, 5561.944380556304, 5607.632501900704, 5730.656160630376, 5754.0, 5794.884102317846, 4545.749983750926, 4925.84745762705, 5409.513143817076, 5674.858128546654, 5705.315362156568, 5885.529157667462, 5947.286325640778, 5974.701264936823, 5978.638437817125, 5985.6408615483215, 3805.83067651436, 3923.625681913495, 3940.3695408734466, 3959.6436320731273, 3992.3213053781506, 4839.588634966068, 5350.384705758887, 5681.801136960186, 5712.457316554921, 5746.195532625352, 4853.684510506806, 5482.230459500261, 5528.9723551381985, 5574.721263936868, 5613.24221230145, 5104.3619547556555, 5144.551957905447, 5224.582033437392, 5537.418571050143, 5586.217929489956, 4336.9132617348105, 4868.245421959602, 4886.828960986349, 5215.982883337712, 5437.238786569963, 4633.467151277646, 5285.656432331886, 5386.649867758583, 5450.264214331015, 5489.945100549103, 5121.615878809179, 5447.9104179165115, 5448.746788238648, 5464.480874316933, 5476.534494567847], "value": 4040.9101576840667, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.8813601687714, 712.3375261007261, 759.4569882533987, 797.2983774278636, 805.4885147931062, 15675.216239188037, 15929.681406371865, 15946.920265398685, 15948.441804536844, 15979.840201597981, 14379.06536075157, 14453.855461445382, 14454.205018723966, 14491.855081449183, 14507.419703211859, 674.4469534981317, 693.3932808792307, 695.1901035293877, 832.629479881453, 836.8661014237723, 864.1013346120028, 864.6714248585561, 870.4690139015228, 871.3508436214997, 873.1704880363649, 920.4523308631353, 921.9769505762342, 922.3773952582014, 923.6028507741669, 923.7782932096316, 914.9908500914972, 915.4827522449795, 915.5834095486571, 917.0096296000304, 923.5428462910869, 518.390890703422, 524.1142469227033, 539.5225225675266, 539.5440852479649, 682.7405585877335, 12451.315177665225, 14251.002429829916, 14747.483838065724, 14790.18653974031, 15089.698206035928, 14294.142351458946, 14864.479743208936, 14872.479463218735, 15065.397384104623, 15093.849061509354, 10380.844287335696, 11788.528458861636, 11892.286462812172, 11986.400679966055, 12043.397830108459, 3862.439946207784, 3864.111254411473, 3955.545112312092, 3964.8414063437426, 4054.493188351456, 101399.49300253525, 102518.0, 102724.48637756838, 110672.0, 111058.44470777594, 11316.717082073013, 11338.149638777124, 11339.536682216502, 11421.42892855354, 11700.473478693479, 22983.885080574826, 23216.0, 23263.53472930532, 23326.65010024851, 23938.52122957531, 8039.35685145187, 8156.388270879707, 8173.223543763333, 8246.505209687439, 8420.578971051424, 4183.748975061506, 4275.615194632498, 4293.785310734451, 4325.091730736581, 4395.84614538496, 3544.0, 3609.242059167604, 3666.6699997000396, 3671.1372827385544, 3679.944800827936, 656.3698849104817, 658.5949640970769, 667.8463953290673, 681.2982627893277, 683.9145106861642, 501.3707796715172, 542.4873494547614, 568.3151802078568, 650.9479241660656, 696.195893742728, 8523.531205783618, 9343.205827504738, 9499.810003799885, 9506.239500839916, 9555.665551705657, 611.1413464082971, 645.9289478157399, 672.2672287206871, 673.6598018000878, 688.3907741648577, 4611.953880461219, 4638.350630911675, 4656.348111264426, 4658.231391820312, 4677.375877320014, 118449.0, 143109.0, 144618.0, 145689.27155364156, 147351.0, 126055.94627885811, 127521.0, 130006.0, 131236.3438182822, 133496.0, 70712.64643676749, 72489.0393085135, 72508.0, 94320.0, 96292.0, 9488.383255088396, 9841.507924603884, 9867.654632087842, 9879.20966322678, 9920.107190352903, 61089.669239691524, 61179.38820611737, 61333.38666613277, 62330.688346557974, 62746.68626656837, 82174.0, 88227.0, 89410.10589894018, 90590.54704726698, 92058.07941920495, 11013.614523491638, 11878.752730963124, 12873.871261287268, 13195.472181112647, 13732.86267137316, 14339.856601433852, 14402.3541734207, 14670.926645366704, 14840.332185051482, 14886.10683359002, 13609.727805444034, 13945.233012184428, 13968.37142328618, 13991.510297139552, 14277.500287490304, 12544.623661290176, 12785.87214127884, 13477.19136851792, 13506.0, 13926.164430134224, 12160.878391215974, 12331.383430828602, 12350.320732359807, 12399.13206075566, 12800.10399272042, 10765.439011343467, 11140.498677559368, 11365.147613928822, 11544.249623774427, 11801.583809942862, 10532.368057916243, 10720.571177152826, 11349.54601815918, 11455.541778328774, 11662.88337116618], "value": 7717.575766194026, "unit": "score"}], "name": "dromaeo_css", "value": 7275.511814118819, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 111.0, 113.0, 114.0, 114.0, 113.0, 112.0, 113.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 111.0, 114.0, 150.0, 111.0, 113.0, 113.0, 111.0, 146.0, 114.0], "value": 123.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 185.0, 186.0, 181.0, 186.0, 179.0, 181.0, 183.0, 184.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 59.0, 86.0, 63.0, 58.0, 60.0, 59.0, 83.0, 58.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 92.0, 69.0, 68.0, 69.0, 93.0, 68.0, 68.0, 89.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 89.0, 84.0, 91.0, 129.0, 118.0, 85.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 92.0, 93.0, 94.0, 93.0, 93.0, 92.0, 95.0, 105.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 131.0, 136.0, 113.0, 86.0, 85.0, 85.0, 85.0, 84.0], "value": 96.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 54.0, 56.0, 56.0, 56.0, 57.0, 57.0, 57.0, 57.0, 57.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 75.0, 74.0, 71.0, 69.0, 70.0, 69.0, 70.0, 71.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 89.0, 89.0, 89.0, 90.0, 92.0, 89.0, 85.0, 112.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 143.0, 153.0, 156.0, 157.0, 145.0, 141.0, 159.0, 142.0], "value": 149.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 170.0, 157.0, 237.0, 240.0, 177.0, 171.0, 173.0, 171.0], "value": 183.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 70.0, 69.0, 68.0, 69.0, 75.0, 79.0, 91.0], "value": 72.9, "unit": "score"}], "name": "kraken", "value": 1472.6000000000001, "alertThreshold": 2.0}]} 16:21:49 INFO - Return code: 0 16:21:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:21:49 INFO - # TBPL SUCCESS # 16:21:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:21:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:21:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:21:49 INFO - 2016-10-01 16:21:49.528 screenresolution[1676:10356] starting screenresolution argv=screenresolution get 16:21:49 INFO - 2016-10-01 16:21:49.531 screenresolution[1676:10356] Display 0: 1600x1200x32@60 16:21:49 INFO - 2016-10-01 16:21:49.537 screenresolution[1677:10359] starting screenresolution argv=screenresolution list 16:21:49 INFO - Available Modes on Display 0 16:21:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:21:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:21:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:21:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:21:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:21:49 INFO - Intel Iris: 16:21:49 INFO - Chipset Model: Intel Iris 16:21:49 INFO - Type: GPU 16:21:49 INFO - Bus: Built-In 16:21:49 INFO - VRAM (Dynamic, Max): 1536 MB 16:21:49 INFO - Vendor: Intel (0x8086) 16:21:49 INFO - Device ID: 0x0a2e 16:21:49 INFO - Revision ID: 0x0009 16:21:49 INFO - Displays: 16:21:49 INFO - Display: 16:21:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:21:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:21:49 INFO - Display Serial Number: 1600x1200 60 16:21:49 INFO - Main Display: Yes 16:21:49 INFO - Mirror: Off 16:21:49 INFO - Online: Yes 16:21:49 INFO - Rotation: Supported 16:21:49 INFO - Return code: 0 16:21:49 INFO - Running post-action listener: _resource_record_post_action 16:21:49 INFO - [mozharness: 2016-10-01 23:21:49.776268Z] Finished run-tests step (success) 16:21:49 INFO - Running post-run listener: _resource_record_post_run 16:21:49 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 33164288; Write bytes: 706447360; Read time: 309; Write time: 1888 16:21:49 INFO - TinderboxPrint: CPU usage
53.6% 16:21:49 INFO - TinderboxPrint: I/O read bytes / time
33,164,288 / 309 16:21:49 INFO - TinderboxPrint: I/O write bytes / time
706,447,360 / 1,888 16:21:49 INFO - TinderboxPrint: CPU idle
2,252.4 (46.4%) 16:21:49 INFO - TinderboxPrint: CPU system
974.1 (20.1%) 16:21:49 INFO - TinderboxPrint: CPU user
1,631.2 (33.6%) 16:21:49 INFO - TinderboxPrint: Swap in / out
436,715,520 / 0 16:21:49 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 393777664; Write bytes: 389003264; Read time: 29427; Write time: 685 16:21:49 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 32222208; Write bytes: 295186432; Read time: 296; Write time: 1156 16:21:50 INFO - Running post-run listener: _upload_blobber_files 16:21:50 INFO - Blob upload gear active. 16:21:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:21:50 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 16:21:50 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:21:50 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:21:50 INFO - (blobuploader) - INFO - Open directory for files ... 16:21:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:21:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:21:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:51 INFO - (blobuploader) - INFO - Done attempting. 16:21:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:21:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:21:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:52 INFO - (blobuploader) - INFO - Done attempting. 16:21:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:21:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:21:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:55 INFO - (blobuploader) - INFO - Done attempting. 16:21:55 INFO - (blobuploader) - INFO - Iteration through files over. 16:21:55 INFO - Return code: 0 16:21:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:21:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:21:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef4813e50b039ee33a8837e28c653df965ec7e5ebe742f915c06635d15ca7f782ddb69d1e6fdb0c755beb827be41e7b16b5662e34b11394c28c9d5b8dd5e0b9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95620344e4b554703daf0fc08a548af946524bcb2148cbe4d78e5fd996e068caf90924320f92a1d62473b2dc273f3c21285486810a2815c5411380de91e9dce1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d87ceb5e6be06970446a9255cab18e7fda84de502db139f25d2765370b5319de2b192cbf39aef47b48b4abf05e796cbfc3e1aaf82bcc75e04f591d30e50a358"} 16:21:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:21:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:21:55 INFO - Contents: 16:21:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef4813e50b039ee33a8837e28c653df965ec7e5ebe742f915c06635d15ca7f782ddb69d1e6fdb0c755beb827be41e7b16b5662e34b11394c28c9d5b8dd5e0b9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95620344e4b554703daf0fc08a548af946524bcb2148cbe4d78e5fd996e068caf90924320f92a1d62473b2dc273f3c21285486810a2815c5411380de91e9dce1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d87ceb5e6be06970446a9255cab18e7fda84de502db139f25d2765370b5319de2b192cbf39aef47b48b4abf05e796cbfc3e1aaf82bcc75e04f591d30e50a358"} 16:21:55 INFO - Running post-run listener: copy_logs_to_upload_dir 16:21:55 INFO - Copying logs to upload dir... 16:21:55 INFO - mkdir: /builds/slave/test/build/upload/logs 16:21:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.969030 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-01 16:21:55.625228) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-01 16:21:55.628482) ========= 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.Z3IFf4QAvz/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.esJgi7MNTH/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef4813e50b039ee33a8837e28c653df965ec7e5ebe742f915c06635d15ca7f782ddb69d1e6fdb0c755beb827be41e7b16b5662e34b11394c28c9d5b8dd5e0b9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95620344e4b554703daf0fc08a548af946524bcb2148cbe4d78e5fd996e068caf90924320f92a1d62473b2dc273f3c21285486810a2815c5411380de91e9dce1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d87ceb5e6be06970446a9255cab18e7fda84de502db139f25d2765370b5319de2b192cbf39aef47b48b4abf05e796cbfc3e1aaf82bcc75e04f591d30e50a358"} build_url:https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011314 build_url: 'https://queue.taskcluster.net/v1/task/V-x2h-T0TR-Cj6aaZKA6LQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef4813e50b039ee33a8837e28c653df965ec7e5ebe742f915c06635d15ca7f782ddb69d1e6fdb0c755beb827be41e7b16b5662e34b11394c28c9d5b8dd5e0b9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95620344e4b554703daf0fc08a548af946524bcb2148cbe4d78e5fd996e068caf90924320f92a1d62473b2dc273f3c21285486810a2815c5411380de91e9dce1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d87ceb5e6be06970446a9255cab18e7fda84de502db139f25d2765370b5319de2b192cbf39aef47b48b4abf05e796cbfc3e1aaf82bcc75e04f591d30e50a358"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-01 16:21:55.665253) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 16:21:55.666081) ========= 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.Z3IFf4QAvz/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.esJgi7MNTH/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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.005204 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 16:21:55.720210) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-01 16:21:55.720597) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-01 16:21:55.721028) ========= ========= Total master_lag: 0.19 =========