builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0374 starttime: 1475105097.63 results: success (0) buildid: 20160928132620 builduid: d6148a2ded8944819ab1d128997b11ab revision: 409769321b119013afc6fd41345605f4a188bcf3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 16:24:57.632704) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 16:24:57.633127) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 16:24:57.633406) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 16:24:57.658172) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 16:24:57.658519) ========= 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.aQe2DOv3e7/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.EziylGosw2/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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-09-28 16:24:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 21.2M=0.001s 2016-09-28 16:24:57 (21.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096086 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 16:24:57.778356) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 16:24:57.778774) ========= 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.aQe2DOv3e7/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.EziylGosw2/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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.026278 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 16:24:57.827546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-28 16:24:57.828302) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 409769321b119013afc6fd41345605f4a188bcf3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 409769321b119013afc6fd41345605f4a188bcf3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aQe2DOv3e7/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.EziylGosw2/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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-09-28 16:24:57,979 truncating revision to first 12 chars 2016-09-28 16:24:57,979 Setting DEBUG logging. 2016-09-28 16:24:57,979 attempt 1/10 2016-09-28 16:24:57,979 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/409769321b11?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-28 16:25:00,228 unpacking tar archive at: autoland-409769321b11/testing/mozharness/ program finished with exit code 0 elapsedTime=2.529771 ========= master_lag: 0.31 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-28 16:25:00.669351) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 16:25:00.669724) ========= script_repo_revision: 409769321b119013afc6fd41345605f4a188bcf3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 16:25:00.670119) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 16:25:00.670387) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 16:25:00.689131) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-28 16:25:00.689393) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aQe2DOv3e7/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.EziylGosw2/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:25:00 INFO - MultiFileLogger online at 20160928 16:25:00 in /builds/slave/test 16:25:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 16:25:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:25:00 INFO - {'append_to_log': False, 16:25:00 INFO - 'base_work_dir': '/builds/slave/test', 16:25:00 INFO - 'blob_upload_branch': 'Autoland', 16:25:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:25:00 INFO - 'branch': 'Autoland', 16:25:00 INFO - 'buildbot_json_path': 'buildprops.json', 16:25:00 INFO - 'config_files': ('talos/mac_config.py',), 16:25:00 INFO - 'default_actions': ('clobber', 16:25:00 INFO - 'read-buildbot-config', 16:25:00 INFO - 'download-and-extract', 16:25:00 INFO - 'populate-webroot', 16:25:00 INFO - 'create-virtualenv', 16:25:00 INFO - 'install', 16:25:00 INFO - 'run-tests'), 16:25:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:25:00 INFO - 'download_minidump_stackwalk': True, 16:25:00 INFO - 'download_symbols': 'ondemand', 16:25:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:25:00 INFO - 'tooltool.py': '/tools/tooltool.py', 16:25:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:25:00 INFO - '/tools/misc-python/virtualenv.py')}, 16:25:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:25:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:25:00 INFO - 'installer_path': 'installer.exe', 16:25:00 INFO - 'log_level': 'info', 16:25:00 INFO - 'log_name': 'talos', 16:25:00 INFO - 'log_to_console': True, 16:25:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:25:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:25:00 INFO - 'opt_config_files': (), 16:25:00 INFO - 'pip_index': False, 16:25:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:25:00 INFO - 'cmd': ('bash', 16:25:00 INFO - '-c', 16:25:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:25:00 INFO - 'enabled': True, 16:25:00 INFO - 'halt_on_failure': False, 16:25:00 INFO - 'name': 'check_screen_resolution'},), 16:25:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:25:00 INFO - 'cmd': ('bash', 16:25:00 INFO - '-c', 16:25:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:25:00 INFO - 'enabled': True, 16:25:00 INFO - 'halt_on_failure': False, 16:25:00 INFO - 'name': 'check_screen_resolution'},), 16:25:00 INFO - 'run_cmd_checks_enabled': True, 16:25:00 INFO - 'sps_profile': False, 16:25:00 INFO - 'sps_profile_interval': 0, 16:25:00 INFO - 'suite': 'dromaeojs', 16:25:00 INFO - 'system_bits': '32', 16:25:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:25:00 INFO - 'title': 't-yosemite-r7-0374', 16:25:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:25:00 INFO - 'use_talos_json': True, 16:25:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:25:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:25:00 INFO - 'work_dir': 'build'} 16:25:00 INFO - [mozharness: 2016-09-28 23:25:00.960521Z] Running clobber step. 16:25:00 INFO - Running pre-action listener: _resource_record_pre_action 16:25:00 INFO - Running main action method: clobber 16:25:00 INFO - rmtree: /builds/slave/test/build 16:25:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:25:03 INFO - Running post-action listener: _resource_record_post_action 16:25:03 INFO - [mozharness: 2016-09-28 23:25:03.153139Z] Finished clobber step (success) 16:25:03 INFO - [mozharness: 2016-09-28 23:25:03.153389Z] Running read-buildbot-config step. 16:25:03 INFO - Running pre-action listener: _resource_record_pre_action 16:25:03 INFO - Running main action method: read_buildbot_config 16:25:03 INFO - Using buildbot properties: 16:25:03 INFO - { 16:25:03 INFO - "project": "", 16:25:03 INFO - "product": "firefox", 16:25:03 INFO - "script_repo_revision": "production", 16:25:03 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 16:25:03 INFO - "repository": "", 16:25:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 16:25:03 INFO - "buildid": "20160928132620", 16:25:03 INFO - "pgo_build": "False", 16:25:03 INFO - "basedir": "/builds/slave/test", 16:25:03 INFO - "buildnumber": 198, 16:25:03 INFO - "slavename": "t-yosemite-r7-0374", 16:25:03 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 16:25:03 INFO - "platform": "yosemite_r7", 16:25:03 INFO - "scheduler": "tests-autoland-macosx64-talos", 16:25:03 INFO - "branch": "autoland", 16:25:03 INFO - "repo_path": "integration/autoland", 16:25:03 INFO - "revision": "409769321b119013afc6fd41345605f4a188bcf3", 16:25:03 INFO - "stage_platform": "macosx64", 16:25:03 INFO - "builduid": "d6148a2ded8944819ab1d128997b11ab", 16:25:03 INFO - "slavebuilddir": "test" 16:25:03 INFO - } 16:25:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 16:25:03 INFO - Running post-action listener: _resource_record_post_action 16:25:03 INFO - [mozharness: 2016-09-28 23:25:03.159150Z] Finished read-buildbot-config step (success) 16:25:03 INFO - [mozharness: 2016-09-28 23:25:03.159373Z] Running download-and-extract step. 16:25:03 INFO - Running pre-action listener: _resource_record_pre_action 16:25:03 INFO - Running main action method: download_and_extract 16:25:03 INFO - mkdir: /builds/slave/test/build/tests 16:25:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:03 INFO - trying https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 16:25:03 INFO - Downloading https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/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:25:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/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:25:04 INFO - Downloaded 1199 bytes. 16:25:04 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:25:04 INFO - Using the following test package requirements: 16:25:04 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:25:04 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:25:04 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 16:25:04 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:25:04 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 16:25:04 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:25:04 INFO - u'jsshell-mac.zip'], 16:25:04 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:25:04 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 16:25:04 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:25:04 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:25:04 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 16:25:04 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:25:04 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 16:25:04 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:25:04 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 16:25:04 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:25:04 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 16:25:04 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:25:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:25:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:25:04 INFO - Fetch https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:25:05 INFO - Http code: 200 16:25:05 INFO - Content-Encoding: None 16:25:05 INFO - Content-Type: application/zip 16:25:05 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 16:25:05 INFO - x-amz-cf-id: wPtRo5RofWDcICNEUsyHyfBN9kl7GpbE6rnqL71Nk4K27KIIuUmnng== 16:25:05 INFO - x-amz-version-id: GwNDkONqQHs6g5sv.slKD2.fs1zL8_fn 16:25:05 INFO - x-cache: Hit from cloudfront 16:25:05 INFO - Expected file size: 16473057 16:25:05 INFO - Obtained file size: 16473057 16:25:11 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:25:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:25:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:25:11 INFO - Fetch https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:25:12 INFO - Http code: 200 16:25:12 INFO - Content-Encoding: None 16:25:12 INFO - Content-Type: application/zip 16:25:12 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 16:25:12 INFO - x-amz-cf-id: Dq91eW_xswtS4WpA8ySV4dsCuaiCFMqdg2Eko96gXMMP73-WzlDTdw== 16:25:12 INFO - x-amz-version-id: GwNDkONqQHs6g5sv.slKD2.fs1zL8_fn 16:25:12 INFO - x-cache: Hit from cloudfront 16:25:12 INFO - Expected file size: 16473057 16:25:12 INFO - Obtained file size: 16473057 16:25:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 16:25:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:25:19 INFO - Fetch https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 16:25:20 INFO - Http code: 200 16:25:20 INFO - Content-Encoding: None 16:25:20 INFO - Content-Type: application/zip 16:25:20 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 16:25:20 INFO - x-amz-cf-id: hkiUTS2i1dsC1NRzp5yNl9rE8bQTX2hWcxW_vFksOdGtRBjX5z8JQw== 16:25:20 INFO - x-amz-version-id: fRDcPLB2ymzRFdFcanuDD7huvBvkoY1r 16:25:20 INFO - x-cache: Miss from cloudfront 16:25:21 INFO - Expected file size: 14414067 16:25:21 INFO - Obtained file size: 14414067 16:25:21 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:25:21 INFO - trying https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:25:21 INFO - Downloading https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 16:25:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/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:25:24 INFO - Downloaded 111809631 bytes. 16:25:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:25:24 INFO - mkdir: /builds/slave/test/properties 16:25:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:25:24 INFO - Writing to file /builds/slave/test/properties/build_url 16:25:24 INFO - Contents: 16:25:24 INFO - build_url:https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:25:25 INFO - Running post-action listener: _resource_record_post_action 16:25:25 INFO - Running post-action listener: set_extra_try_arguments 16:25:25 INFO - [mozharness: 2016-09-28 23:25:25.047707Z] Finished download-and-extract step (success) 16:25:25 INFO - [mozharness: 2016-09-28 23:25:25.047822Z] Running populate-webroot step. 16:25:25 INFO - Running pre-action listener: _resource_record_pre_action 16:25:25 INFO - Running main action method: populate_webroot 16:25:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:25:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:25:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:25:25 INFO - '--tppagecycles', 16:25:25 INFO - '7'], 16:25:25 INFO - 'tests': ['tsvgm']}}, 16:25:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:25:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:25:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:25:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:25:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:25:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:25:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:25:25 INFO - 'talos_options': ['--disable-e10s'], 16:25:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:25:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:25:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:25:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:25:25 INFO - 'talos_options': ['--disable-e10s'], 16:25:25 INFO - 'tests': ['damp', 'tps']}, 16:25:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:25:25 INFO - 'tests': ['damp', 'tps']}, 16:25:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:25:25 INFO - 'tests': ['dromaeo_dom']}, 16:25:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:25:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:25:25 INFO - 'tests': ['basic_compositor_video']}, 16:25:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:25:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:25:25 INFO - 'tests': ['a11yr', 16:25:25 INFO - 'ts_paint', 16:25:25 INFO - 'tpaint', 16:25:25 INFO - 'sessionrestore', 16:25:25 INFO - 'sessionrestore_no_auto_restore', 16:25:25 INFO - 'tabpaint']}, 16:25:25 INFO - 'other-e10s': {'tests': ['a11yr', 16:25:25 INFO - 'ts_paint', 16:25:25 INFO - 'tpaint', 16:25:25 INFO - 'sessionrestore', 16:25:25 INFO - 'sessionrestore_no_auto_restore', 16:25:25 INFO - 'tabpaint']}, 16:25:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:25:25 INFO - 'ts_paint', 16:25:25 INFO - 'tpaint', 16:25:25 INFO - 'sessionrestore', 16:25:25 INFO - 'sessionrestore_no_auto_restore', 16:25:25 INFO - 'tabpaint']}, 16:25:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:25:25 INFO - 'ts_paint', 16:25:25 INFO - 'tpaint', 16:25:25 INFO - 'sessionrestore', 16:25:25 INFO - 'sessionrestore_no_auto_restore', 16:25:25 INFO - 'tabpaint']}, 16:25:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:25:25 INFO - 'tests': ['a11yr', 16:25:25 INFO - 'ts_paint', 16:25:25 INFO - 'tpaint', 16:25:25 INFO - 'sessionrestore', 16:25:25 INFO - 'sessionrestore_no_auto_restore', 16:25:25 INFO - 'tabpaint']}, 16:25:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:25:25 INFO - 'tests': ['a11yr', 16:25:25 INFO - 'ts_paint', 16:25:25 INFO - 'tpaint', 16:25:25 INFO - 'sessionrestore', 16:25:25 INFO - 'sessionrestore_no_auto_restore', 16:25:25 INFO - 'tabpaint']}, 16:25:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:25:25 INFO - 'tests': ['tsvgx', 16:25:25 INFO - 'tsvgr_opacity', 16:25:25 INFO - 'tart', 16:25:25 INFO - 'tscrollx', 16:25:25 INFO - 'cart']}, 16:25:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:25:25 INFO - 'tsvgr_opacity', 16:25:25 INFO - 'tart', 16:25:25 INFO - 'tscrollx', 16:25:25 INFO - 'cart']}, 16:25:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:25:25 INFO - 'talos_options': ['--disable-e10s'], 16:25:25 INFO - 'tests': ['tp5o']}, 16:25:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:25:25 INFO - 'tests': ['tp5o']}, 16:25:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:25:25 INFO - 'talos_options': ['--disable-e10s', 16:25:25 INFO - '--xperf_path', 16:25:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:25:25 INFO - 'tests': ['tp5n']}, 16:25:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:25:25 INFO - 'talos_options': ['--xperf_path', 16:25:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:25:25 INFO - 'tests': ['tp5n']}}, 16:25:25 INFO - 'talos.zip': {'path': '', 16:25:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:25:25 INFO - Running post-action listener: _resource_record_post_action 16:25:25 INFO - [mozharness: 2016-09-28 23:25:25.066565Z] Finished populate-webroot step (success) 16:25:25 INFO - [mozharness: 2016-09-28 23:25:25.066695Z] Running create-virtualenv step. 16:25:25 INFO - Running pre-action listener: _resource_record_pre_action 16:25:25 INFO - Running main action method: create_virtualenv 16:25:25 INFO - Creating virtualenv /builds/slave/test/build/venv 16:25:25 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:25:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:25:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:25:25 INFO - Using real prefix '/tools/python27' 16:25:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:25:25 INFO - Installing distribute.............................................................................................................................................................................................done. 16:25:28 INFO - Installing pip.................done. 16:25:28 INFO - Return code: 0 16:25:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:25:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:25:29 INFO - Reading from file tmpfile_stdout 16:25:29 INFO - Output received: 16:25:29 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:25:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:29 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 0x10ba1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b990570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa75ad61ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b82ed30>, '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 0x10b654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b826b20>, '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.aQe2DOv3e7/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.EziylGosw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:25:29 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:25:29 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:25:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQe2DOv3e7/Render', 16:25:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:29 INFO - 'HOME': '/Users/cltbld', 16:25:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:29 INFO - 'LOGNAME': 'cltbld', 16:25:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:29 INFO - 'MOZ_NO_REMOTE': '1', 16:25:29 INFO - 'NO_EM_RESTART': '1', 16:25:29 INFO - 'PAGER': '/bin/cat', 16:25:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:29 INFO - 'PWD': '/builds/slave/test', 16:25:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:29 INFO - 'SHELL': '/bin/bash', 16:25:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EziylGosw2/Listeners', 16:25:29 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 16:25:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:29 INFO - 'USER': 'cltbld', 16:25:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:29 INFO - 'XPC_FLAGS': '0x0', 16:25:29 INFO - 'XPC_SERVICE_NAME': '0', 16:25:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:29 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:25:29 INFO - Cleaning up... 16:25:29 INFO - Return code: 0 16:25:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:29 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 0x10ba1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b990570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa75ad61ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b82ed30>, '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 0x10b654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b826b20>, '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.aQe2DOv3e7/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.EziylGosw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:25:29 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:25:29 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:25:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQe2DOv3e7/Render', 16:25:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:29 INFO - 'HOME': '/Users/cltbld', 16:25:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:29 INFO - 'LOGNAME': 'cltbld', 16:25:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:29 INFO - 'MOZ_NO_REMOTE': '1', 16:25:29 INFO - 'NO_EM_RESTART': '1', 16:25:29 INFO - 'PAGER': '/bin/cat', 16:25:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:29 INFO - 'PWD': '/builds/slave/test', 16:25:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:29 INFO - 'SHELL': '/bin/bash', 16:25:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EziylGosw2/Listeners', 16:25:29 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 16:25:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:29 INFO - 'USER': 'cltbld', 16:25:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:29 INFO - 'XPC_FLAGS': '0x0', 16:25:29 INFO - 'XPC_SERVICE_NAME': '0', 16:25:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:29 INFO - Downloading/unpacking psutil>=3.1.1 16:25:29 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:25:29 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:25:29 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:25:29 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:25:29 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:25:29 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:25:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:25:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:25:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:25:32 INFO - Installing collected packages: psutil 16:25:32 INFO - Running setup.py install for psutil 16:25:33 INFO - building 'psutil._psutil_osx' extension 16:25:33 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:25:33 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:25:33 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:25:33 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:25:33 INFO - building 'psutil._psutil_posix' extension 16:25:33 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:25:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:25:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:25:33 INFO - ^ 16:25:33 INFO - 1 warning generated. 16:25:33 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:25:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:25:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:25:34 INFO - Successfully installed psutil 16:25:34 INFO - Cleaning up... 16:25:34 INFO - Return code: 0 16:25:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:25: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:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25: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:25:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ba1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b990570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa75ad61ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b82ed30>, '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 0x10b654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b826b20>, '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.aQe2DOv3e7/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.EziylGosw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:25: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:25: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 mozsystemmonitor==0.3 16:25:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQe2DOv3e7/Render', 16:25:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:34 INFO - 'HOME': '/Users/cltbld', 16:25:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:34 INFO - 'LOGNAME': 'cltbld', 16:25:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:34 INFO - 'MOZ_NO_REMOTE': '1', 16:25:34 INFO - 'NO_EM_RESTART': '1', 16:25:34 INFO - 'PAGER': '/bin/cat', 16:25:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:34 INFO - 'PWD': '/builds/slave/test', 16:25:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:34 INFO - 'SHELL': '/bin/bash', 16:25:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EziylGosw2/Listeners', 16:25:34 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 16:25:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:34 INFO - 'USER': 'cltbld', 16:25:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:34 INFO - 'XPC_FLAGS': '0x0', 16:25:34 INFO - 'XPC_SERVICE_NAME': '0', 16:25:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:25:34 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:25:34 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:25:34 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:25:34 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:25:34 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:25:34 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:25:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:25:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:25:37 INFO - Installing collected packages: mozsystemmonitor 16:25:37 INFO - Running setup.py install for mozsystemmonitor 16:25:37 INFO - Successfully installed mozsystemmonitor 16:25:37 INFO - Cleaning up... 16:25:37 INFO - Return code: 0 16:25:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ba1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b990570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa75ad61ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b82ed30>, '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 0x10b654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b826b20>, '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.aQe2DOv3e7/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.EziylGosw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:25:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:25:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:25:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQe2DOv3e7/Render', 16:25:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:37 INFO - 'HOME': '/Users/cltbld', 16:25:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:37 INFO - 'LOGNAME': 'cltbld', 16:25:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:37 INFO - 'MOZ_NO_REMOTE': '1', 16:25:37 INFO - 'NO_EM_RESTART': '1', 16:25:37 INFO - 'PAGER': '/bin/cat', 16:25:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:37 INFO - 'PWD': '/builds/slave/test', 16:25:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:37 INFO - 'SHELL': '/bin/bash', 16:25:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EziylGosw2/Listeners', 16:25:37 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 16:25:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:37 INFO - 'USER': 'cltbld', 16:25:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:37 INFO - 'XPC_FLAGS': '0x0', 16:25:37 INFO - 'XPC_SERVICE_NAME': '0', 16:25:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:37 INFO - Downloading/unpacking jsonschema==2.5.1 16:25:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:25:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:25:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:25:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:25:39 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:25:39 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:25:39 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:25:39 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:25:39 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:25:39 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:25:40 INFO - Downloading functools32-3.2.3-2.tar.gz 16:25:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:25:40 INFO - warning: no files found matching '*.txt' 16:25:40 INFO - no previously-included directories found matching 'build' 16:25:40 INFO - no previously-included directories found matching 'dist' 16:25:40 INFO - no previously-included directories found matching '.git*' 16:25:40 INFO - Installing collected packages: jsonschema, functools32 16:25:40 INFO - Running setup.py install for functools32 16:25:40 INFO - warning: no files found matching '*.txt' 16:25:40 INFO - no previously-included directories found matching 'build' 16:25:40 INFO - no previously-included directories found matching 'dist' 16:25:40 INFO - no previously-included directories found matching '.git*' 16:25:40 INFO - Successfully installed jsonschema functools32 16:25:40 INFO - Cleaning up... 16:25:40 INFO - Return code: 0 16:25:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:40 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 0x10ba1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b990570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa75ad61ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b82ed30>, '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 0x10b654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b826b20>, '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.aQe2DOv3e7/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.EziylGosw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:25:40 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:25:40 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:25:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQe2DOv3e7/Render', 16:25:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:40 INFO - 'HOME': '/Users/cltbld', 16:25:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:40 INFO - 'LOGNAME': 'cltbld', 16:25:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:40 INFO - 'MOZ_NO_REMOTE': '1', 16:25:40 INFO - 'NO_EM_RESTART': '1', 16:25:40 INFO - 'PAGER': '/bin/cat', 16:25:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:40 INFO - 'PWD': '/builds/slave/test', 16:25:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:40 INFO - 'SHELL': '/bin/bash', 16:25:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EziylGosw2/Listeners', 16:25:40 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 16:25:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:40 INFO - 'USER': 'cltbld', 16:25:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:40 INFO - 'XPC_FLAGS': '0x0', 16:25:40 INFO - 'XPC_SERVICE_NAME': '0', 16:25:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:25:40 INFO - Cleaning up... 16:25:40 INFO - Return code: 0 16:25:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:40 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 0x10ba1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b990570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa75ad61ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b82ed30>, '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 0x10b654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b826b20>, '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.aQe2DOv3e7/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.EziylGosw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:25:40 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:25:40 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:25:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQe2DOv3e7/Render', 16:25:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:40 INFO - 'HOME': '/Users/cltbld', 16:25:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:40 INFO - 'LOGNAME': 'cltbld', 16:25:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:40 INFO - 'MOZ_NO_REMOTE': '1', 16:25:40 INFO - 'NO_EM_RESTART': '1', 16:25:40 INFO - 'PAGER': '/bin/cat', 16:25:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:40 INFO - 'PWD': '/builds/slave/test', 16:25:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:40 INFO - 'SHELL': '/bin/bash', 16:25:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EziylGosw2/Listeners', 16:25:40 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 16:25:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:40 INFO - 'USER': 'cltbld', 16:25:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:40 INFO - 'XPC_FLAGS': '0x0', 16:25:40 INFO - 'XPC_SERVICE_NAME': '0', 16:25:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:41 INFO - Downloading/unpacking blobuploader==1.2.4 16:25:41 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:25:41 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:25:41 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:25:41 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:25:41 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:25:41 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:25:43 INFO - Downloading blobuploader-1.2.4.tar.gz 16:25:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:25:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:25:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:25:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:25:44 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:25:44 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:25:44 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:25:44 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:25:44 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:25:44 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:25:44 INFO - Downloading docopt-0.6.1.tar.gz 16:25:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:25:44 INFO - Installing collected packages: blobuploader, requests, docopt 16:25:44 INFO - Running setup.py install for blobuploader 16:25:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:25:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:25:44 INFO - Running setup.py install for requests 16:25:45 INFO - Running setup.py install for docopt 16:25:45 INFO - Successfully installed blobuploader requests docopt 16:25:45 INFO - Cleaning up... 16:25:45 INFO - Return code: 0 16:25:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:25:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:45 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 0x10ba1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b990570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa75ad61ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b82ed30>, '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 0x10b654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b826b20>, '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.aQe2DOv3e7/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.EziylGosw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:25:45 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:25:45 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:25:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQe2DOv3e7/Render', 16:25:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:45 INFO - 'HOME': '/Users/cltbld', 16:25:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:45 INFO - 'LOGNAME': 'cltbld', 16:25:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:45 INFO - 'MOZ_NO_REMOTE': '1', 16:25:45 INFO - 'NO_EM_RESTART': '1', 16:25:45 INFO - 'PAGER': '/bin/cat', 16:25:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:45 INFO - 'PWD': '/builds/slave/test', 16:25:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:45 INFO - 'SHELL': '/bin/bash', 16:25:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EziylGosw2/Listeners', 16:25:45 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 16:25:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:45 INFO - 'USER': 'cltbld', 16:25:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:45 INFO - 'XPC_FLAGS': '0x0', 16:25:45 INFO - 'XPC_SERVICE_NAME': '0', 16:25:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:25:45 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-2ejwSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:25:46 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-f5u6tE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:25:46 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-0EeWvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:25:46 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-_d6hTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:25:46 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-tOpjM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:25:46 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-XJfT4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:25:46 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-U0V9Qs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:25:46 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-lo9la0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:25:46 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-Nml_VT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:25:46 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-99iCIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:25:46 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-lw1aBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:25:47 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-gazdFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:25:47 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-2MAOTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:25:47 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-fC9ic5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:25:47 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-hi_TUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:25:47 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-7bFaqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:25:47 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-k749p7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:25:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:25:47 INFO - Running setup.py install for manifestparser 16:25:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:25:47 INFO - Running setup.py install for mozcrash 16:25:47 INFO - Running setup.py install for mozdebug 16:25:48 INFO - Running setup.py install for mozdevice 16:25:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:25:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:25:48 INFO - Running setup.py install for mozfile 16:25:48 INFO - Running setup.py install for mozhttpd 16:25:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:25:48 INFO - Running setup.py install for mozinfo 16:25:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:25:48 INFO - Running setup.py install for mozInstall 16:25:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:25:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:25:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:25:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:25:48 INFO - Running setup.py install for mozleak 16:25:48 INFO - Running setup.py install for mozlog 16:25:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:25:49 INFO - Running setup.py install for moznetwork 16:25:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:25:49 INFO - Running setup.py install for mozprocess 16:25:49 INFO - Running setup.py install for mozprofile 16:25:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:25:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:25:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:25:49 INFO - Running setup.py install for mozrunner 16:25:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:25:49 INFO - Running setup.py install for mozscreenshot 16:25:49 INFO - Running setup.py install for moztest 16:25:50 INFO - Running setup.py install for mozversion 16:25:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:25:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:25:50 INFO - Cleaning up... 16:25:50 INFO - Return code: 0 16:25:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:50 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 0x10ba1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b990570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa75ad61ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b82ed30>, '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 0x10b654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b826b20>, '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.aQe2DOv3e7/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.EziylGosw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:25:50 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:25:50 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:25:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQe2DOv3e7/Render', 16:25:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:50 INFO - 'HOME': '/Users/cltbld', 16:25:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:50 INFO - 'LOGNAME': 'cltbld', 16:25:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:50 INFO - 'MOZ_NO_REMOTE': '1', 16:25:50 INFO - 'NO_EM_RESTART': '1', 16:25:50 INFO - 'PAGER': '/bin/cat', 16:25:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:50 INFO - 'PWD': '/builds/slave/test', 16:25:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:50 INFO - 'SHELL': '/bin/bash', 16:25:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EziylGosw2/Listeners', 16:25:50 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 16:25:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:50 INFO - 'USER': 'cltbld', 16:25:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:50 INFO - 'XPC_FLAGS': '0x0', 16:25:50 INFO - 'XPC_SERVICE_NAME': '0', 16:25:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:25:50 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-hEwbjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:25:50 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:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:25:50 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-Bw4LKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:25:50 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:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:25:50 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-1hhV1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:25:50 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:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:25:50 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-WI6HOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:25:50 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:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:25:50 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-sJhEsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:25:51 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:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:25:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-7QkY5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:25:51 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:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:25:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-FurZCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:25:51 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:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:25:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-x9pI5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:25:51 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:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:25:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-__nTdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:25:51 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:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:25:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-3p3W7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:25:51 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:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:25:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-3b_Df3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:25:51 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:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:25:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-fXyX8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:25:51 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:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:25:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-8psc53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:25:51 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:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:25:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-J0W2wb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:25:51 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:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:25:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-MrVpwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:25:52 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:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:25:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-nVJrlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:25:52 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:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:25:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-HXPZVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:25:52 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:25:52 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:25:52 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:25:52 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:25:52 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:25:52 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:25:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:25:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:54 INFO - Downloading blessings-1.6.tar.gz 16:25:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:25:54 INFO - Installing collected packages: blessings 16:25:54 INFO - Running setup.py install for blessings 16:25:54 INFO - Successfully installed blessings 16:25:54 INFO - Cleaning up... 16:25:55 INFO - Return code: 0 16:25:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:25:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:25:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:25:55 INFO - Reading from file tmpfile_stdout 16:25:55 INFO - Current package versions: 16:25:55 INFO - blessings == 1.6 16:25:55 INFO - blobuploader == 1.2.4 16:25:55 INFO - docopt == 0.6.1 16:25:55 INFO - functools32 == 3.2.3-2 16:25:55 INFO - jsonschema == 2.5.1 16:25:55 INFO - manifestparser == 1.1 16:25:55 INFO - mozInstall == 1.12 16:25:55 INFO - mozcrash == 0.17 16:25:55 INFO - mozdebug == 0.1 16:25:55 INFO - mozdevice == 0.48 16:25:55 INFO - mozfile == 1.2 16:25:55 INFO - mozhttpd == 0.7 16:25:55 INFO - mozinfo == 0.9 16:25:55 INFO - mozleak == 0.1 16:25:55 INFO - mozlog == 3.4 16:25:55 INFO - moznetwork == 0.27 16:25:55 INFO - mozprocess == 0.23 16:25:55 INFO - mozprofile == 0.28 16:25:55 INFO - mozrunner == 6.12 16:25:55 INFO - mozscreenshot == 0.1 16:25:55 INFO - mozsystemmonitor == 0.3 16:25:55 INFO - moztest == 0.7 16:25:55 INFO - mozversion == 1.4 16:25:55 INFO - psutil == 3.1.1 16:25:55 INFO - requests == 1.2.3 16:25:55 INFO - wsgiref == 0.1.2 16:25:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:25:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:56 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 0x10ba1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b990570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa75ad61ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b82ed30>, '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 0x10b654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b826b20>, '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.aQe2DOv3e7/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.EziylGosw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:25:56 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:25:56 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:25:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQe2DOv3e7/Render', 16:25:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:56 INFO - 'HOME': '/Users/cltbld', 16:25:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:56 INFO - 'LOGNAME': 'cltbld', 16:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:56 INFO - 'MOZ_NO_REMOTE': '1', 16:25:56 INFO - 'NO_EM_RESTART': '1', 16:25:56 INFO - 'PAGER': '/bin/cat', 16:25:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:56 INFO - 'PWD': '/builds/slave/test', 16:25:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:56 INFO - 'SHELL': '/bin/bash', 16:25:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EziylGosw2/Listeners', 16:25:56 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 16:25:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:56 INFO - 'USER': 'cltbld', 16:25:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:56 INFO - 'XPC_FLAGS': '0x0', 16:25:56 INFO - 'XPC_SERVICE_NAME': '0', 16:25:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:56 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:25:56 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:25:56 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:25:56 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:25:56 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:25:56 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:25:56 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:25:56 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:25:56 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:25:56 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:25:56 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:25:56 INFO - Cleaning up... 16:25:56 INFO - Return code: 0 16:25:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ba1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b990570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa75ad61ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b82ed30>, '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 0x10b654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b826b20>, '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.aQe2DOv3e7/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.EziylGosw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:25:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:25:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:25:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQe2DOv3e7/Render', 16:25:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:56 INFO - 'HOME': '/Users/cltbld', 16:25:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:56 INFO - 'LOGNAME': 'cltbld', 16:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:56 INFO - 'MOZ_NO_REMOTE': '1', 16:25:56 INFO - 'NO_EM_RESTART': '1', 16:25:56 INFO - 'PAGER': '/bin/cat', 16:25:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:56 INFO - 'PWD': '/builds/slave/test', 16:25:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:56 INFO - 'SHELL': '/bin/bash', 16:25:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EziylGosw2/Listeners', 16:25:56 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 16:25:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:56 INFO - 'USER': 'cltbld', 16:25:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:56 INFO - 'XPC_FLAGS': '0x0', 16:25:56 INFO - 'XPC_SERVICE_NAME': '0', 16:25:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:25:56 INFO - Cleaning up... 16:25:56 INFO - Return code: 0 16:25:56 INFO - Running post-action listener: _resource_record_post_action 16:25:56 INFO - Running post-action listener: _start_resource_monitoring 16:25:57 INFO - Starting resource monitoring. 16:25:57 INFO - [mozharness: 2016-09-28 23:25:57.025094Z] Finished create-virtualenv step (success) 16:25:57 INFO - [mozharness: 2016-09-28 23:25:57.026115Z] Running install step. 16:25:57 INFO - Running pre-action listener: _resource_record_pre_action 16:25:57 INFO - Running main action method: install 16:25:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:25:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:25:57 INFO - Reading from file tmpfile_stdout 16:25:57 INFO - Detecting whether we're running mozinstall >=1.0... 16:25:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:25:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:25:57 INFO - Reading from file tmpfile_stdout 16:25:57 INFO - Output received: 16:25:57 INFO - Usage: mozinstall [options] installer 16:25:57 INFO - Options: 16:25:57 INFO - -h, --help show this help message and exit 16:25:57 INFO - -d DEST, --destination=DEST 16:25:57 INFO - Directory to install application into. [default: 16:25:57 INFO - "/builds/slave/test"] 16:25:57 INFO - --app=APP Application being installed. [default: firefox] 16:25:57 INFO - mkdir: /builds/slave/test/build/application 16:25:57 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:25:57 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:26:31 INFO - Reading from file tmpfile_stdout 16:26:31 INFO - Output received: 16:26:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:26:31 INFO - Running post-action listener: _resource_record_post_action 16:26:31 INFO - [mozharness: 2016-09-28 23:26:31.357996Z] Finished install step (success) 16:26:31 INFO - [mozharness: 2016-09-28 23:26:31.358133Z] Running run-tests step. 16:26:31 INFO - Running pre-action listener: _resource_record_pre_action 16:26:31 INFO - Running main action method: run_tests 16:26:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:26:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:26:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:26:31 INFO - 2016-09-28 16:26:31.372 screenresolution[1641:7351] starting screenresolution argv=screenresolution get 16:26:31 INFO - 2016-09-28 16:26:31.375 screenresolution[1641:7351] Display 0: 1600x1200x32@60 16:26:31 INFO - 2016-09-28 16:26:31.381 screenresolution[1642:7353] starting screenresolution argv=screenresolution list 16:26:31 INFO - Available Modes on Display 0 16:26:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:26:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:26:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:26:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:26:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:26:31 INFO - Intel Iris: 16:26:31 INFO - Chipset Model: Intel Iris 16:26:31 INFO - Type: GPU 16:26:31 INFO - Bus: Built-In 16:26:31 INFO - VRAM (Dynamic, Max): 1536 MB 16:26:31 INFO - Vendor: Intel (0x8086) 16:26:31 INFO - Device ID: 0x0a2e 16:26:31 INFO - Revision ID: 0x0009 16:26:31 INFO - Displays: 16:26:31 INFO - Display: 16:26:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:26:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:26:31 INFO - Display Serial Number: 1600x1200 60 16:26:31 INFO - Main Display: Yes 16:26:31 INFO - Mirror: Off 16:26:31 INFO - Online: Yes 16:26:31 INFO - Rotation: Supported 16:26:31 INFO - Return code: 0 16:26:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:26:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:26:31 INFO - Python 2.7.3 16:26:31 INFO - Return code: 0 16:26:31 INFO - grabbing minidump binary from tooltool 16:26:31 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:26:31 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 0x10b82ed30>, '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 0x10b654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b826b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:26:31 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:26:31 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:26:31 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:26:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:26:31 INFO - Return code: 0 16:26:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:26:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:26:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:26:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:26:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:26:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0374', '--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:26:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0374 --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:26:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQe2DOv3e7/Render', 16:26:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:26:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:26:31 INFO - 'HOME': '/Users/cltbld', 16:26:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:26:31 INFO - 'LOGNAME': 'cltbld', 16:26:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:26:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:26:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:31 INFO - 'MOZ_NO_REMOTE': '1', 16:26:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:26:31 INFO - 'NO_EM_RESTART': '1', 16:26:31 INFO - 'PAGER': '/bin/cat', 16:26:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:26:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:26:31 INFO - 'PWD': '/builds/slave/test', 16:26:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:26:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:26:31 INFO - 'SHELL': '/bin/bash', 16:26:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EziylGosw2/Listeners', 16:26:31 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 16:26:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:26:31 INFO - 'USER': 'cltbld', 16:26:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:26:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:26:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:31 INFO - 'XPC_FLAGS': '0x0', 16:26:31 INFO - 'XPC_SERVICE_NAME': '0', 16:26:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:26:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0374', '--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:26:31 INFO - mozversion application_buildid: 20160928132620 16:26:31 INFO - mozversion application_changeset: 409769321b119013afc6fd41345605f4a188bcf3 16:26:31 INFO - mozversion application_display_name: Nightly 16:26:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:26:31 INFO - mozversion application_name: Firefox 16:26:31 INFO - mozversion application_remotingname: firefox 16:26:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:26:31 INFO - mozversion application_vendor: Mozilla 16:26:31 INFO - mozversion application_version: 52.0a1 16:26:31 INFO - mozversion platform_buildid: 20160928132620 16:26:31 INFO - mozversion platform_changeset: 409769321b119013afc6fd41345605f4a188bcf3 16:26:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:26:31 INFO - mozversion platform_version: 52.0a1 16:26:31 INFO - using testdate: 1475105191 16:26:31 INFO - actual date: 1475105191 16:26:31 INFO - starting webserver on 'localhost:49266' 16:26:31 INFO - SUITE-START | Running 2 tests 16:26:31 INFO - TEST-START | dromaeo_css 16:26:31 INFO - operating with platform_type : mac_ 16:26:31 INFO - Initialising browser for dromaeo_css test... 16:26:31 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmprI9veE/profile http://localhost:49266/getInfo.html) 16:26:34 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 16:26:34 INFO - PROCESS | 1652 | colorDepth:24 16:26:34 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 16:26:34 INFO - PROCESS | 1652 | __metrics 16:26:34 INFO - PROCESS | 1652 | 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:26:34 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:26:35 INFO - TEST-INFO | 1652: exit 0 16:26:35 INFO - Browser initialized. 16:26:35 INFO - Running cycle 1/1 for dromaeo_css test... 16:26:35 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmprI9veE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:26:47 INFO - PROCESS | 1654 | RSS: Main: 236064768 16:26:47 INFO - PROCESS | 1654 | 16:27:38 INFO - PROCESS | 1654 | 1475105258014 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:29:50 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 16:29:51 INFO - PROCESS | 1654 | RSS: Main: 368685056 16:29:51 INFO - PROCESS | 1654 | 16:32:55 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 16:32:55 INFO - PROCESS | 1654 | RSS: Main: 371757056 16:32:55 INFO - PROCESS | 1654 | 16:35:57 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 16:35:58 INFO - PROCESS | 1654 | RSS: Main: 335892480 16:35:58 INFO - PROCESS | 1654 | 16:39:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 16:39:01 INFO - PROCESS | 1654 | RSS: Main: 338583552 16:39:01 INFO - PROCESS | 1654 | 16:42:04 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 16:42:05 INFO - PROCESS | 1654 | RSS: Main: 331448320 16:42:05 INFO - PROCESS | 1654 | 16:45:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 16:45:09 INFO - PROCESS | 1654 | RSS: Main: 539328512 16:45:09 INFO - PROCESS | 1654 | 16:45:09 INFO - PROCESS | 1654 | __start_tp_report 16:45:09 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 16:45:09 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 16:45:09 INFO - PROCESS | 1654 | |i|pagename|runs| 16:45:09 INFO - PROCESS | 1654 | |0;dojo.html;3339.3154403347307;3348.9330213395733;3361.4789707595314;3368.663133686633;3398.932021359573;5632.155176723493;5688.857778555538;5718.685472299027;5782.942170578293;5803.738831752566;5691.886162276752;5725.885482290351;5826.912596311048;5835.679037652927;5844.678542680151;5120.487951204868;5188.221766734982;5206.401263854648;5234.057869583474;5259.658122222062;5311.548518375924;5318.388385335678;5318.627696061278;5332.546733527654;5334.733263336837;5131.947950670104;5289.920651190235;5303.018941495817;5334.946650533484;5367.892642147155;4442.244818380883;5188.22176673502;5213;5221.216817477371;5245.973770131163;5182.818601348972;5199.298094757197;5209.557187639055;5250.501202385767;5282.97358513205;5744.913826292609;5753.741081651336;5776.162456443834;5795.217645617872;5796.478316951495;5225.399079105913;6095.801078121664;6121.178620693087;6269.2476902771505;6293.433590976788;6226.2839773426185;6241.843953901143;6297.685115744194;6299.275583307933;6317.4314311711705;5847.941520584783;5852.688188165694;5867.911981320283;5879.735411906475;5920.259967504062;6145.754169833246;6191.102290167901;6199.814005579839;6231.937680623227;6298.779542715983;6153.692315384213;6199.256089269316;6208.689565521706;6216.347283535164;6220.875582488416;6259;6270.2789179244055;6280.246370435577;6293.96853015732;6306.148669929501;6042.214512113397;6118.314626315956;6244.937550624527;6246.875062498815;6250.9998400255745;5755.6834374108985;6133.610107582747;6219.067139928957;6252.218472690914;6279.497640188774;4922.507749225048;4928.039032388721;4931.260310953314;4933.988532350932;4953.23224900141;4434.667399945017;4469.240229161051;4493.977530112328;4502.302143167814;4534.161180181677;4865.270209468537;5717.313922329347;5720.141978703228;5757.165211044375;5816.389279125631;6291.307956124822;6311.526635502264;6322.462590679752;6339.049142628643;6342.714577844008;5794.449527294901;5836.737346819404;5837.357890632027;5850.736716847667;5897.174395584621;5275.709835958983;5370.704611246419;5403.8108666196495;5410.702411367334;5424.972875135678;6217.564770466114;6225.719842607003;6244.000959846403;6279.246490421178;6293.559450838397;6279.780207692709;6281.780137695158;6286.685665716787;6290.842728931813;6291.245050593958;5291.796220999749;6185.474234690102;6205.689715514116;6256.562040657111;6274.811755647338;6081.966065768742;6102.489251635809;6116.853194528166;6135.233095863017;6156.230471191101;6313.179286692701;6315.968420158054;6320.936790632035;6322.083297921868;6322.841928951813;6047.5464340173785;6135.815925522242;6152.230971128609;6168.1056246844855;6168.414000669929;5923.348431672599;5973.074173503114;5983.072623743153;6135.263768347827;6151.169592105009;5110.693358398508;5960.552958528041;6003.6397816131175;6022;6032.517398608013;6135.815925522063;6137.508999280136;6151.784687535915;6154.292256390393;6165.84585385351;5831.35855055952;5995.070764031582;6008.188894499187;6054.66699331541;6067.54493412987 16:45:09 INFO - PROCESS | 1654 | |1;ext.html;489.2538878210728;503.240107437769;524.1194792748184;525.8054519827664;548.4625067433913;29099.85450072749;39689.603103968955;41122.38316425248;41159.58840411595;41389.58610413895;33946.49080263798;41944.790276048654;41987.79006104973;42098.789506052504;42111.947201319985;8628.827423451527;9596.328257022029;9765;9791.020897910188;9794.657186998456;8765.167309105625;11990.340531270776;12287.569935052275;12302;12305.876941230608;12061.638150855444;12117.151799374047;12141.028717702562;12154.209976351509;12155.696107597292;8717.259032982203;8940.8658870117;12123.090768192376;12209.3749843784;12227.510899564008;11544.711382215512;11951.940240298829;11977.880221197764;12094.939525302405;12109.03127749778;40307.7984610078;40712;40749;40754.38868416976;40786.881967705085;3498.3528047311074;3723.2925744108534;3756.9436458453147;4037.9192416151805;4132.958670413287;29787.851060744775;30874.536881946788;31246.21884452884;31320;31373.37253254945;3111.175538482304;3248.626407963091;3266.0365192268337;3269.967300327014;3300.1089705779546;483411;484010;486675;487330.56334718456;493107;56341.71829140828;56939;56948.71525642345;56953;57028.863412512714;60036;60039.399606004255;60081.39918600846;60171;60196.69901650551;15820.60448488797;16047.438339658056;16065.919670401574;16088.276027578788;16091.195440228175;11369.602063927723;11679.357635329969;11698;12649.43077561512;12701.174423662433;14623.85376146225;14871.851281487046;14932.925335373471;15172.696546069237;15230.847691522942;7094.964525177341;7117.537360071578;7138.072050633385;7177.6770045348085;7215.917612358188;872.8079822439054;977.413551868873;984.640606178754;1012.8632634594383;1044.8641676581997;26127.346816329744;29173.41653166925;29528.852355738512;29553.85223073871;29706.338784768093;992.3450522655157;1024.1857723110145;1075.763332066944;1093.3877028863703;1128.2497139402205;15046.32291546883;15165.620859478602;15174.317155728018;15257.135734762558;15492.767608485881;631383;635973;636958;637790;639225;56822.43177568254;56867.57831054174;56900;56955.14567281411;56956.14565781516;1236.1347057059913;1274.7832868412206;1281.3529167770216;1384.3216823756286;1407.3315175291655;26224.60663090017;28381.858090709415;30020.69979300179;31256.749730011456;31938.36123277568;50959.74520127524;51607.48392516177;51660.741696291276;51663.48336516587;51767;582521;583999;584508;587153;588785.5139556105;22677.86610669427;23269.534609307382;25030.249092527254;25587;37909.81045094757;46112.53887461082;46149.538504614524;46972.7651361741;47027.589172324886;49987.75006124946;31469.8426507866;37423.812880935424;38287.80856095702;39011.6098839008;39392;37553.81223093867;38031.429528557645;38697.61302387053;39177.6082239174;39409.40885886657;37766.57232829713;37876.863694089225;38550;38686.22627547377;38760.22479550337;32862.68549258003;33323.1669208262;34153.316933661685;34421.48367774535;34495.482567761;24748.50502989894;24856.627150592394;25036.874815625804;25241.621375679013;25539.9784008645 16:45:09 INFO - PROCESS | 1654 | |2;jquery.html;354.8846624846925;370.50167524679296;373.89157144428117;381.6317253850034;403.06891081601503;15226.619334516628;15491.380344786208;17106.40127595533;20691.068901899413;21304.786952130475;19805.603887922232;20319;20436.080376383063;20629;21486.033128509214;4789.401324834387;5766.769329226847;6102.786402475915;6138.570300078996;6174.660393678346;5953.464188223059;6089.817305480843;6092.238470191226;6093.421124993119;6104.053871649901;3869.361555343365;4082.122343696105;6316.936830631681;6637.568558043736;6676.3657452542175;5970;6061.090836374535;6722.663866806664;6728.461723062168;6733.8989915151315;5888.764449422061;5903;5908.172855800191;5909.349971503174;5913.9112913306335;13279.800802987962;17385.565360865952;25499.617505737428;26178.34554136143;26466.470670586674;2180.967285490719;2187.7484089329773;2195.132922495621;2293.942651433711;2799.664040315155;9831.115199631997;9898.257630677728;9910.306278560503;10412.11497022754;10422.84365734507;1798.6672465593906;1851.8148185181508;1870.4014715291112;1885.981140188608;1946.4744518979937;113570;116710.24934625988;117320.41339793331;117853;118803.13865713202;11156.442177891073;11202.831957520644;11357.829632555518;11420.143489238175;11572.76854462906;14311.713765724628;14335.068220565838;14346.426142954166;14347.35436905321;14359.85640143606;8283.337333013465;8405.873911891327;8411.537365444838;8415.452995555392;8549.572521373906;8399.674167803587;8403.604972582785;8422;8554.315654747605;8591.914080859111;4452.799624016927;4474.485434175112;4474.843380481732;4489.461264648263;4513.029698614813;1837.4579499047813;1879.4267748336888;1882.5481884347657;1883.9717404238947;1888.5835623282537;363.914480097177;395.39109770952984;870.7823044238941;937.6483818568037;960.2619259140977;9363.672271470467;10983.61557345505;11988.220765650205;12002.039836813034;12027.458764355624;414.7034870067916;425.4511679932852;426.27533193571054;437.04069567299473;445.0431572119903;5080.161773307363;5630.690312032795;5909.940900590939;6047.15339852421;6075.635461872302;566150;615701;616020;624943.8752806297;634105;87998;105820.47089764656;108524.4573777126;132479;138001.999999998;437.44226111707826;438.7871882137161;447.47198305999234;449.16679559417463;450.6822690003605;11003.064739497233;11185.272957257752;11406.543738250377;11441.370724610226;11533.365664888512;51620;53651.46348536621;55153.72423137858;55359;55384.44615553793;79146;82145;82879.17120828714;84525;85205;7673.155952845071;7778.844423111619;7957.244061814118;8119.512829230265;8275.420720549504;9920.503974801375;11717.472713727731;12426.316552589695;12557.623271301876;12796.808047879476;11271.718207044889;11296.49165787525;11415.086793056702;11446.084313255085;11473.50844390224;11110.277831940897;11173.608923687632;11421.257618254787;11440.48517816717;11603.709907252049;11337;11367.261128026983;11727.41362931833;11791.705207369885;11825.704357391134;6919.827004324932;7735.458517903693;7866.488678235896;8101.068377136705;8304.086550479322;10476.528556214836;11075.889241107487;11285.830712539482;11287.492062857027;11462.541498339973 16:45:09 INFO - PROCESS | 1654 | |3;mootools.html;1372.5607805502243;1537.3389442539708;1545.7604071368937;1748.9650206995861;1893.867429279951;7791.181925897777;8252.546109963949;8533.743987680364;8788.604512796923;8824.249938755198;8639.697610583615;8727.607257673404;8730;8735.91264087359;8751;2331.731850837154;2363.7872591466767;2367.1241640593003;2367.6566897799894;2369.123424332999;2070.81362677359;2324.8256380771427;2331.6036273833406;2346.4485845826216;2373.92878213653;2339.9298021059394;2359.5634807560655;2361.669366288721;2373.7151541815006;2386.474975505386;2309.411100169446;2339.543788961153;2348.201611452106;2352.8353015288935;2361.2325994052003;2332.6734257204007;2336.5210131922913;2337.8012868905994;2340.379799353173;2342.3792694935855;8619.956900215459;8792.560371981375;8804.987426445976;8807.64769409223;8823.029466758651;5143.3570803649545;5153.381594208681;5300.284461597674;5302.814401495929;5331.413544510139;3433.141714571357;3569.161247106928;3647.689946354563;3655.232401195752;3664.9816750916075;1070.336391437306;1073.785242951405;1075.317173594765;1094.3816743539874;1095.901368876797;11523.654290371385;11569.942150289195;11683.123765717604;11695.181337306392;11730.002949749281;4385;4405.163019026375;4565.452145742533;4588.082383523242;4593.816247350135;9139.680111196076;9147.862782058275;9150.039245879087;9160.175584197454;9160.725178244664;5199.506046925536;5203.687778733288;5207.973960130175;5212.218167274939;5214.618126196561;4152.231837110145;4395.890102747457;4545.113702827917;4567;4873.439554451284;3680.3191409589313;3695.6858667013066;3762.9623703762613;3769.359208934488;3770.490983717218;974.7319487141093;1129.1192869561755;1138.8348689439986;1139.1114930354338;1147.713071732067;684.7226873116396;868.4962721621445;895.4985208283382;900.2752784008935;949.0272470717432;4685.344051832746;4955.677880937727;5632.577556683265;5790.536757059425;5919;889.2219308105408;901.0538934119148;911.2209061252667;920.0569416348296;946.7065209784988;3312;3751.8874433767032;3820.369639009588;3849.826757795906;3938.6849052075763;10427.739306517398;10863.51114199863;10873.021428071512;10878.184136189817;10878.238523303451;10830.296030757976;10839.295445796155;10849.457527123612;10883.891161088446;10892.237543372046;8032.0361556612415;8335.291265290602;8340.332773378115;8347.29048030912;8364.623591938378;4854.5873600744335;5005.299258103942;5024.623153263593;5835.570285280133;5869.941300586939;9410.153086222273;9634.470104144058;9665.806683866143;9745.902540974783;10023.148032417326;9386;9836.754081148028;10112.241581881535;10203.132733717717;10255.33340332876;6996.370326670624;7192.280771922766;7347.412207023533;7561.621918903923;7588.506747061423;8207.097219305751;8282.834343313221;8324.542150181798;8382.622781974947;8407.11725268838;6903.344182302673;7148.606826624586;7600.657970391456;7647.38820894316;7668.424868134801;7305.452091093083;7377;7451.701931922662;7454.813629659085;7572.20491848348;6800.489963252875;6985.790426287016;7079.858402831812;7084.61034643079;7086.751963681247;4883.096627123993;4956.256561515802;4964.95735895466;5012.498750124958;5153.278541004187;4226.393970291392;4322.265214913535;4338.566143385636;4342.2835232186335;4431.800568974469 16:45:09 INFO - PROCESS | 1654 | |4;prototype.html;299.5357196345664;300.3077905427988;309.865208634244;314.763927054709;316.5584010305622;5065.898682026357;5218.843434696955;5268.894622107556;5324.440933701963;5378.784848606051;4879.024195160973;5077.227406238134;5169.844904652857;5188;5263.315708988424;3343.328335832084;4096.528899176603;4121.505419349667;4121.732087414323;4144.274751918402;3966.52401711795;4084.550699423076;4088.6524645405175;4129.525104612963;4145.917081658365;4024.5170579530495;4027.2750904837117;4046.736962097469;4078.490188726409;4086;4036.919261614751;4050.5341885683083;4053.8378464861516;4080.2655522006025;4082.183563287325;3327.5175099610665;3395.2700169463437;3544.8759293424605;3564.322778672044;3571.7320351275184;3878.4376265441633;4822.373091498118;4866.635002374836;5157.381114266274;5184.455632158607;3868.9881261744017;4654.464736555272;4657;4687.624990000826;4692.756419548822;4938.08645400602;5273.44042771128;5582.302212223472;5596.972015139939;5597.44025597437;3573.9456860226296;3657.817109144559;3776.6412190841825;3929.4302326162547;3933.468981687493;5310.44240354761;5812.215350927613;5820.388859169769;5832.591718579701;5844.772597754477;3170.3500782339565;3412.2663627320244;3672.6510981456718;4008.6392224699916;4010.8997275067773;3582.390993531106;3607.5851277102943;4040.4141399496907;4073.348264277701;4092.8485066499225;4419.580139886705;4465.665075119314;4663.603593694574;4666.463356714022;4682.4381074271305;4680.555347242007;5626.690532020696;5637.379888212294;5666.231955749463;5680.715964201773;1277.163457935054;1296.3647812571771;1331.9943442700699;1332.3404914567352;1369.4795977528474;887.8901373283396;909.7816524034184;912.9406588571721;949.0556895888498;950.2825366848124;4569.634429245652;4821.662483626113;5160.458151894072;5225.764840582183;5234.450382709837;5284.445133260952;5436.456354364532;5672.602917795797;5731.369549349568;5746.597738158289;5100;5125.948740512622;5128.666636668605;5141.0232055909255;5189.844304670791;2075.107703687429;2296.81625469962;2440.133752517866;2451.33813870254;2483.888225029878;4236.406903033578;4813.518648135158;4957.429895562056;5421.295231619864;6013;5534.446555344514;6015.639061656315;6026.9095963560585;6049.939500605025;6070.6357618543025;5264.605154613343;5881.617694849821;6037.54718396122;6065.878682426327;6104.755809767559;4830.154722923404;4878.438979517259;5482.835514934558;5581.972090139523;5822.796202132905;4962.156433406401;5052.494750524918;5292.338457692788;5774.307083150049;5794.159846822272;5670.659760414388;5693.772249110155;5928.525717942468;6034.366391528826;6053.697315134314;3568.232829941575;3583.7849729016343;3681.5457894132205;3894.7857867817543;3913.784741839226;5092.7777333440445;5625.268715067015;5845.707714614167;5855.970720146543;5895.351511333664;4667.346571480063;4799;5390.110631745718;5454.809081682122;5674.06377947634;4666.8133274668635;5653.745581448762;5661.490465858093;5668.291463567054;5675.517581005495;4546.74992875395;5136.1268584340005;5273.525382715574;5415.566754659539;5555.722213889208;5699.523290792696;5711.571632127524;5717.771289148387;5728.656280623176;5742.827715168552;4489.653104068831;5261.5264626183825;5444.455554444682;5543.77824887;5556.388797232382 16:45:09 INFO - PROCESS | 1654 | |5;yui.html;713.8215446138464;767.5318055985848;786.3787607789847;820.9425340226179;842.4355681693265;13693.726125477484;14614.48849290275;14826.851731482682;15601.609959751011;15928.76106858398;14133.787993180085;14274.429022839075;14342.92828535856;14370.353334099964;14382.640433989129;831.7296878514477;862.9482231066124;863.386995233385;863.4689665855485;865.7878819689184;691.4399336537396;712.7149140343852;715.9713611455536;853.6372041882206;877.5831480046991;919.6689191890914;921.4195057114009;922.0226559846584;923.4966943016072;925.453982150531;915.3775432705758;917.6788124156559;919.3242966419709;920.1764420843341;922.4327038871113;536.1448489658987;551.242042191986;584.2667452347278;655.4003087175229;675.3955210086993;14832.851671483362;15220.086794792238;15229.847701522955;15236.162011089385;15250.932434729573;13733;14131;14137.363818628186;14190.148591084568;14381;12852.293123878182;12876.163049401759;13059.347032648424;13312.800307995389;13558.186508809504;4608.5621865922685;4644.4658864230705;4662.3239630253765;4845.685030473008;4850.587700045465;116884;119056.40471797585;120157;120857.39571302263;122162;11141.72145696364;11239.100871930226;11277.943610281896;11508;11657.417129143509;22605.80066584662;22621.20825771123;22646;22678;22704.31887043391;7968.442209045311;8090.4336696431865;8122.390820688472;8138.674453021931;8420.705275315335;4471.910561788811;4490.236659767847;4560.931586026212;4667.276572131325;4698.295255711602;3313.950290745641;3447.172678557178;3451.0854623524788;3453.2230248193955;3459.8270086495572;627.4321738826369;680.4726337088796;694.5034300475185;707.7699747582056;735.5219107580116;571.7284289962317;591.6479694581717;626.0859145647315;686.4165459359505;686.8694947959972;9422.858657120149;9636.75908102303;9744.951275243578;9872.457014864107;9883.950580247052;653.5882394091786;668.5086461450829;677.9559328643622;691.2534462780185;699.7655785311875;4500.639948804088;4751.858449351679;4764.737939413366;4787.952120478821;4796.932816191858;152302;155905;158903;160524.39475605328;162955;134242;135413.01098329775;135772.32113839567;138587;141816;116553.41723291499;116779;116815;117862.99999999828;120380;9162.633494660138;9184.311176661644;9186.265098791948;9209.079092090737;9251.306152038489;54982.72508637431;62874;65340;65793.34206657873;67113.66443167753;96589.03410965801;97187;97665;98403;98759.50620246853;12160;12241.938790305992;12313.56902508408;12338.629841104781;12859.0998630095;11624.767504650028;13562.79655805144;14752.188629625474;14968.625784355478;15322.616934576725;12195.451204695633;13407.128536645087;14200.644983875485;14622.903282254087;14631;11781.52873885035;13991.650208744455;14176.291185440892;14227.288635567973;14371.20958347301;13427.462901483832;13701.725965480435;13851.376688049264;14072.155670659384;14307.070040447337;11325.15061370417;11413;11489.368084755419;11499.885001150216;11711.414429278331;6460.717580472125;9423.340366174301;10304.433256170683;10488.790224195625;10896.291741036806 16:45:09 INFO - PROCESS | 1654 | __end_tp_report 16:45:09 INFO - PROCESS | 1654 | __start_cc_report 16:45:09 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1248 16:45:09 INFO - PROCESS | 1654 | __end_cc_report 16:45:09 INFO - PROCESS | 1654 | __startTimestamp1475106309022__endTimestamp 16:45:09 INFO - PROCESS | 1654 | 16:45:09 INFO - PROCESS | 1654 | ------- Summary: start ------- 16:45:09 INFO - PROCESS | 1654 | Number of tests: 6 16:45:09 INFO - PROCESS | 1654 | 16:45:09 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5745.15 Median:5973.07 stddev:643.33 (10.8%) stddev-sans-first:617.00 16:45:09 INFO - PROCESS | 1654 | Values: 3339.3 3348.9 3361.5 3368.7 3398.9 5632.2 5688.9 5718.7 5782.9 5803.7 5691.9 5725.9 5826.9 5835.7 5844.7 5120.5 5188.2 5206.4 5234.1 5259.7 5311.5 5318.4 5318.6 5332.5 5334.7 5131.9 5289.9 5303.0 5334.9 5367.9 4442.2 5188.2 5213.0 5221.2 5246.0 5182.8 5199.3 5209.6 5250.5 5283.0 5744.9 5753.7 5776.2 5795.2 5796.5 5225.4 6095.8 6121.2 6269.2 6293.4 6226.3 6241.8 6297.7 6299.3 6317.4 5847.9 5852.7 5867.9 5879.7 5920.3 6145.8 6191.1 6199.8 6231.9 6298.8 6153.7 6199.3 6208.7 6216.3 6220.9 6259.0 6270.3 6280.2 6294.0 6306.1 6042.2 6118.3 6244.9 6246.9 6251.0 5755.7 6133.6 6219.1 6252.2 6279.5 4922.5 4928.0 4931.3 4934.0 4953.2 4434.7 4469.2 4494.0 4502.3 4534.2 4865.3 5717.3 5720.1 5757.2 5816.4 6291.3 6311.5 6322.5 6339.0 6342.7 5794.4 5836.7 5837.4 5850.7 5897.2 5275.7 5370.7 5403.8 5410.7 5425.0 6217.6 6225.7 6244.0 6279.2 6293.6 6279.8 6281.8 6286.7 6290.8 6291.2 5291.8 6185.5 6205.7 6256.6 6274.8 6082.0 6102.5 6116.9 6135.2 6156.2 6313.2 6316.0 6320.9 6322.1 6322.8 6047.5 6135.8 6152.2 6168.1 6168.4 5923.3 5973.1 5983.1 6135.3 6151.2 5110.7 5960.6 6003.6 6022.0 6032.5 6135.8 6137.5 6151.8 6154.3 6165.8 5831.4 5995.1 6008.2 6054.7 6067.5 16:45:09 INFO - PROCESS | 1654 | 16:45:09 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:70061.12 Median:37821.72 stddev:153069.27 (404.7%) stddev-sans-first:153409.54 16:45:09 INFO - PROCESS | 1654 | Values: 489.3 503.2 524.1 525.8 548.5 29099.9 39689.6 41122.4 41159.6 41389.6 33946.5 41944.8 41987.8 42098.8 42111.9 8628.8 9596.3 9765.0 9791.0 9794.7 8765.2 11990.3 12287.6 12302.0 12305.9 12061.6 12117.2 12141.0 12154.2 12155.7 8717.3 8940.9 12123.1 12209.4 12227.5 11544.7 11951.9 11977.9 12094.9 12109.0 40307.8 40712.0 40749.0 40754.4 40786.9 3498.4 3723.3 3756.9 4037.9 4133.0 29787.9 30874.5 31246.2 31320.0 31373.4 3111.2 3248.6 3266.0 3270.0 3300.1 483411.0 484010.0 486675.0 487330.6 493107.0 56341.7 56939.0 56948.7 56953.0 57028.9 60036.0 60039.4 60081.4 60171.0 60196.7 15820.6 16047.4 16065.9 16088.3 16091.2 11369.6 11679.4 11698.0 12649.4 12701.2 14623.9 14871.9 14932.9 15172.7 15230.8 7095.0 7117.5 7138.1 7177.7 7215.9 872.8 977.4 984.6 1012.9 1044.9 26127.3 29173.4 29528.9 29553.9 29706.3 992.3 1024.2 1075.8 1093.4 1128.2 15046.3 15165.6 15174.3 15257.1 15492.8 631383.0 635973.0 636958.0 637790.0 639225.0 56822.4 56867.6 56900.0 56955.1 56956.1 1236.1 1274.8 1281.4 1384.3 1407.3 26224.6 28381.9 30020.7 31256.7 31938.4 50959.7 51607.5 51660.7 51663.5 51767.0 582521.0 583999.0 584508.0 587153.0 588785.5 22677.9 23269.5 25030.2 25587.0 37909.8 46112.5 46149.5 46972.8 47027.6 49987.8 31469.8 37423.8 38287.8 39011.6 39392.0 37553.8 38031.4 38697.6 39177.6 39409.4 37766.6 37876.9 38550.0 38686.2 38760.2 32862.7 33323.2 34153.3 34421.5 34495.5 24748.5 24856.6 25036.9 25241.6 25540.0 16:45:09 INFO - PROCESS | 1654 | 16:45:09 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34344.52 Median:399.23 stddev:101926.09 (25530.7%) stddev-sans-first:102180.06 16:45:09 INFO - PROCESS | 1654 | Values: 354.9 370.5 373.9 381.6 403.1 15226.6 15491.4 17106.4 20691.1 21304.8 19805.6 20319.0 20436.1 20629.0 21486.0 4789.4 5766.8 6102.8 6138.6 6174.7 5953.5 6089.8 6092.2 6093.4 6104.1 3869.4 4082.1 6316.9 6637.6 6676.4 5970.0 6061.1 6722.7 6728.5 6733.9 5888.8 5903.0 5908.2 5909.3 5913.9 13279.8 17385.6 25499.6 26178.3 26466.5 2181.0 2187.7 2195.1 2293.9 2799.7 9831.1 9898.3 9910.3 10412.1 10422.8 1798.7 1851.8 1870.4 1886.0 1946.5 113570.0 116710.2 117320.4 117853.0 118803.1 11156.4 11202.8 11357.8 11420.1 11572.8 14311.7 14335.1 14346.4 14347.4 14359.9 8283.3 8405.9 8411.5 8415.5 8549.6 8399.7 8403.6 8422.0 8554.3 8591.9 4452.8 4474.5 4474.8 4489.5 4513.0 1837.5 1879.4 1882.5 1884.0 1888.6 363.9 395.4 870.8 937.6 960.3 9363.7 10983.6 11988.2 12002.0 12027.5 414.7 425.5 426.3 437.0 445.0 5080.2 5630.7 5909.9 6047.2 6075.6 566150.0 615701.0 616020.0 624943.9 634105.0 87998.0 105820.5 108524.5 132479.0 138002.0 437.4 438.8 447.5 449.2 450.7 11003.1 11185.3 11406.5 11441.4 11533.4 51620.0 53651.5 55153.7 55359.0 55384.4 79146.0 82145.0 82879.2 84525.0 85205.0 7673.2 7778.8 7957.2 8119.5 8275.4 9920.5 11717.5 12426.3 12557.6 12796.8 11271.7 11296.5 11415.1 11446.1 11473.5 11110.3 11173.6 11421.3 11440.5 11603.7 11337.0 11367.3 11727.4 11791.7 11825.7 6919.8 7735.5 7866.5 8101.1 8304.1 10476.5 11075.9 11285.8 11287.5 11462.5 16:45:09 INFO - PROCESS | 1654 | 16:45:09 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5601.05 Median:4919.39 stddev:3190.27 (64.9%) stddev-sans-first:3183.40 16:45:09 INFO - PROCESS | 1654 | Values: 1372.6 1537.3 1545.8 1749.0 1893.9 7791.2 8252.5 8533.7 8788.6 8824.2 8639.7 8727.6 8730.0 8735.9 8751.0 2331.7 2363.8 2367.1 2367.7 2369.1 2070.8 2324.8 2331.6 2346.4 2373.9 2339.9 2359.6 2361.7 2373.7 2386.5 2309.4 2339.5 2348.2 2352.8 2361.2 2332.7 2336.5 2337.8 2340.4 2342.4 8620.0 8792.6 8805.0 8807.6 8823.0 5143.4 5153.4 5300.3 5302.8 5331.4 3433.1 3569.2 3647.7 3655.2 3665.0 1070.3 1073.8 1075.3 1094.4 1095.9 11523.7 11569.9 11683.1 11695.2 11730.0 4385.0 4405.2 4565.5 4588.1 4593.8 9139.7 9147.9 9150.0 9160.2 9160.7 5199.5 5203.7 5208.0 5212.2 5214.6 4152.2 4395.9 4545.1 4567.0 4873.4 3680.3 3695.7 3763.0 3769.4 3770.5 974.7 1129.1 1138.8 1139.1 1147.7 684.7 868.5 895.5 900.3 949.0 4685.3 4955.7 5632.6 5790.5 5919.0 889.2 901.1 911.2 920.1 946.7 3312.0 3751.9 3820.4 3849.8 3938.7 10427.7 10863.5 10873.0 10878.2 10878.2 10830.3 10839.3 10849.5 10883.9 10892.2 8032.0 8335.3 8340.3 8347.3 8364.6 4854.6 5005.3 5024.6 5835.6 5869.9 9410.2 9634.5 9665.8 9745.9 10023.1 9386.0 9836.8 10112.2 10203.1 10255.3 6996.4 7192.3 7347.4 7561.6 7588.5 8207.1 8282.8 8324.5 8382.6 8407.1 6903.3 7148.6 7600.7 7647.4 7668.4 7305.5 7377.0 7451.7 7454.8 7572.2 6800.5 6985.8 7079.9 7084.6 7086.8 4883.1 4956.3 4965.0 5012.5 5153.3 4226.4 4322.3 4338.6 4342.3 4431.8 16:45:09 INFO - PROCESS | 1654 | 16:45:09 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4502.06 Median:5071.56 stddev:1406.09 (27.7%) stddev-sans-first:1374.19 16:45:09 INFO - PROCESS | 1654 | Values: 299.5 300.3 309.9 314.8 316.6 5065.9 5218.8 5268.9 5324.4 5378.8 4879.0 5077.2 5169.8 5188.0 5263.3 3343.3 4096.5 4121.5 4121.7 4144.3 3966.5 4084.6 4088.7 4129.5 4145.9 4024.5 4027.3 4046.7 4078.5 4086.0 4036.9 4050.5 4053.8 4080.3 4082.2 3327.5 3395.3 3544.9 3564.3 3571.7 3878.4 4822.4 4866.6 5157.4 5184.5 3869.0 4654.5 4657.0 4687.6 4692.8 4938.1 5273.4 5582.3 5597.0 5597.4 3573.9 3657.8 3776.6 3929.4 3933.5 5310.4 5812.2 5820.4 5832.6 5844.8 3170.4 3412.3 3672.7 4008.6 4010.9 3582.4 3607.6 4040.4 4073.3 4092.8 4419.6 4465.7 4663.6 4666.5 4682.4 4680.6 5626.7 5637.4 5666.2 5680.7 1277.2 1296.4 1332.0 1332.3 1369.5 887.9 909.8 912.9 949.1 950.3 4569.6 4821.7 5160.5 5225.8 5234.5 5284.4 5436.5 5672.6 5731.4 5746.6 5100.0 5125.9 5128.7 5141.0 5189.8 2075.1 2296.8 2440.1 2451.3 2483.9 4236.4 4813.5 4957.4 5421.3 6013.0 5534.4 6015.6 6026.9 6049.9 6070.6 5264.6 5881.6 6037.5 6065.9 6104.8 4830.2 4878.4 5482.8 5582.0 5822.8 4962.2 5052.5 5292.3 5774.3 5794.2 5670.7 5693.8 5928.5 6034.4 6053.7 3568.2 3583.8 3681.5 3894.8 3913.8 5092.8 5625.3 5845.7 5856.0 5895.4 4667.3 4799.0 5390.1 5454.8 5674.1 4666.8 5653.7 5661.5 5668.3 5675.5 4546.7 5136.1 5273.5 5415.6 5555.7 5699.5 5711.6 5717.8 5728.7 5742.8 4489.7 5261.5 5444.5 5543.8 5556.4 16:45:09 INFO - PROCESS | 1654 | 16:45:09 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:26098.93 Median:3452.15 stddev:42361.35 (1227.1%) stddev-sans-first:42437.31 16:45:09 INFO - PROCESS | 1654 | Values: 713.8 767.5 786.4 820.9 842.4 13693.7 14614.5 14826.9 15601.6 15928.8 14133.8 14274.4 14342.9 14370.4 14382.6 831.7 862.9 863.4 863.5 865.8 691.4 712.7 716.0 853.6 877.6 919.7 921.4 922.0 923.5 925.5 915.4 917.7 919.3 920.2 922.4 536.1 551.2 584.3 655.4 675.4 14832.9 15220.1 15229.8 15236.2 15250.9 13733.0 14131.0 14137.4 14190.1 14381.0 12852.3 12876.2 13059.3 13312.8 13558.2 4608.6 4644.5 4662.3 4845.7 4850.6 116884.0 119056.4 120157.0 120857.4 122162.0 11141.7 11239.1 11277.9 11508.0 11657.4 22605.8 22621.2 22646.0 22678.0 22704.3 7968.4 8090.4 8122.4 8138.7 8420.7 4471.9 4490.2 4560.9 4667.3 4698.3 3314.0 3447.2 3451.1 3453.2 3459.8 627.4 680.5 694.5 707.8 735.5 571.7 591.6 626.1 686.4 686.9 9422.9 9636.8 9745.0 9872.5 9884.0 653.6 668.5 678.0 691.3 699.8 4500.6 4751.9 4764.7 4788.0 4796.9 152302.0 155905.0 158903.0 160524.4 162955.0 134242.0 135413.0 135772.3 138587.0 141816.0 116553.4 116779.0 116815.0 117863.0 120380.0 9162.6 9184.3 9186.3 9209.1 9251.3 54982.7 62874.0 65340.0 65793.3 67113.7 96589.0 97187.0 97665.0 98403.0 98759.5 12160.0 12241.9 12313.6 12338.6 12859.1 11624.8 13562.8 14752.2 14968.6 15322.6 12195.5 13407.1 14200.6 14622.9 14631.0 11781.5 13991.7 14176.3 14227.3 14371.2 13427.5 13701.7 13851.4 14072.2 14307.1 11325.2 11413.0 11489.4 11499.9 11711.4 6460.7 9423.3 10304.4 10488.8 10896.3 16:45:09 INFO - PROCESS | 1654 | -------- Summary: end -------- 16:45:09 INFO - PROCESS | 1654 | 16:45:09 INFO - TEST-INFO | 1654: exit 0 16:45:09 INFO - TEST-OK | dromaeo_css | took 1117712ms 16:45:09 INFO - TEST-START | kraken 16:45:09 INFO - operating with platform_type : mac_ 16:45:09 INFO - Initialising browser for kraken test... 16:45:09 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpk8z0ub/profile http://localhost:49266/getInfo.html) 16:45:11 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 16:45:11 INFO - PROCESS | 1664 | colorDepth:24 16:45:11 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 16:45:11 INFO - PROCESS | 1664 | __metrics 16:45:11 INFO - PROCESS | 1664 | 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:45:11 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:45:12 INFO - TEST-INFO | 1664: exit 0 16:45:12 INFO - Browser initialized. 16:45:12 INFO - Running cycle 1/1 for kraken test... 16:45:12 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpk8z0ub/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:45:24 INFO - PROCESS | 1665 | RSS: Main: 236072960 16:45:24 INFO - PROCESS | 1665 | 16:45:27 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 16:45:28 INFO - PROCESS | 1665 | RSS: Main: 294690816 16:45:28 INFO - PROCESS | 1665 | 16:45:31 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 16:45:31 INFO - PROCESS | 1665 | RSS: Main: 293552128 16:45:31 INFO - PROCESS | 1665 | 16:45:35 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 16:45:36 INFO - PROCESS | 1665 | RSS: Main: 386969600 16:45:36 INFO - PROCESS | 1665 | 16:45:38 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 16:45:38 INFO - PROCESS | 1665 | RSS: Main: 288444416 16:45:38 INFO - PROCESS | 1665 | 16:45:40 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:45:41 INFO - PROCESS | 1665 | RSS: Main: 291454976 16:45:41 INFO - PROCESS | 1665 | 16:45:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 16:45:45 INFO - PROCESS | 1665 | RSS: Main: 330125312 16:45:45 INFO - PROCESS | 1665 | 16:45:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 16:45:48 INFO - PROCESS | 1665 | RSS: Main: 329097216 16:45:48 INFO - PROCESS | 1665 | 16:45:51 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 16:45:52 INFO - PROCESS | 1665 | RSS: Main: 326680576 16:45:52 INFO - PROCESS | 1665 | 16:45:53 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:45:54 INFO - PROCESS | 1665 | RSS: Main: 304975872 16:45:54 INFO - PROCESS | 1665 | 16:45:56 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:45:56 INFO - PROCESS | 1665 | RSS: Main: 302718976 16:45:56 INFO - PROCESS | 1665 | 16:45:58 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:45:59 INFO - PROCESS | 1665 | RSS: Main: 302931968 16:45:59 INFO - PROCESS | 1665 | 16:46:02 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:46:03 INFO - PROCESS | 1665 | RSS: Main: 315850752 16:46:03 INFO - PROCESS | 1665 | 16:46:06 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:46:06 INFO - PROCESS | 1665 | RSS: Main: 317960192 16:46:06 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 16:46:08 INFO - PROCESS | 1665 | RSS: Main: 361254912 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | __start_tp_report 16:46:08 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 16:46:08 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 16:46:08 INFO - PROCESS | 1665 | |i|pagename|runs| 16:46:08 INFO - PROCESS | 1665 | |0;ai-astar;113;114;117;132;114;114;113;114;126;167 16:46:08 INFO - PROCESS | 1665 | |1;audio-beat-detection;143;111;109;144;109;113;131;140;146;108 16:46:08 INFO - PROCESS | 1665 | |2;audio-dft;185;183;184;184;183;181;184;179;194;261 16:46:08 INFO - PROCESS | 1665 | |3;audio-fft;78;59;60;88;61;61;59;60;91;59 16:46:08 INFO - PROCESS | 1665 | |4;audio-oscillator;88;94;114;70;70;70;119;98;72;89 16:46:08 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;87;84;86;85;134;88;74;127;134;95 16:46:08 INFO - PROCESS | 1665 | |6;imaging-darkroom;92;93;116;122;93;94;93;109;150;106 16:46:08 INFO - PROCESS | 1665 | |7;imaging-desaturate;86;85;87;87;84;86;85;83;86;85 16:46:08 INFO - PROCESS | 1665 | |8;json-parse-financial;57;55;56;53;56;55;56;54;54;55 16:46:08 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;65;74;69;77;72;69;70;70;69;67 16:46:08 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;88;86;84;83;76;100;94;107;105;106 16:46:08 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;141;137;140;140;137;140;133;136;165;138 16:46:08 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;168;168;169;167;167;168;168;170;168;167 16:46:08 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;68;67;68;68;68;67;67;68;67;66 16:46:08 INFO - PROCESS | 1665 | __end_tp_report 16:46:08 INFO - PROCESS | 1665 | __start_cc_report 16:46:08 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1901 16:46:08 INFO - PROCESS | 1665 | __end_cc_report 16:46:08 INFO - PROCESS | 1665 | __startTimestamp1475106368600__endTimestamp 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | ------- Summary: start ------- 16:46:08 INFO - PROCESS | 1665 | Number of tests: 14 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:122.40 Median:115.50 stddev:16.94 (14.7%) stddev-sans-first:17.62 16:46:08 INFO - PROCESS | 1665 | Values: 113.0 114.0 117.0 132.0 114.0 114.0 113.0 114.0 126.0 167.0 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:125.40 Median:135.50 stddev:16.75 (12.4%) stddev-sans-first:16.52 16:46:08 INFO - PROCESS | 1665 | Values: 143.0 111.0 109.0 144.0 109.0 113.0 131.0 140.0 146.0 108.0 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:191.80 Median:184.00 stddev:24.63 (13.4%) stddev-sans-first:26.00 16:46:08 INFO - PROCESS | 1665 | Values: 185.0 183.0 184.0 184.0 183.0 181.0 184.0 179.0 194.0 261.0 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:67.60 Median:61.00 stddev:12.89 (21.1%) stddev-sans-first:13.12 16:46:08 INFO - PROCESS | 1665 | Values: 78.0 59.0 60.0 88.0 61.0 61.0 59.0 60.0 91.0 59.0 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:80.00 stddev:18.26 (22.8%) stddev-sans-first:19.37 16:46:08 INFO - PROCESS | 1665 | Values: 88.0 94.0 114.0 70.0 70.0 70.0 119.0 98.0 72.0 89.0 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:99.40 Median:85.50 stddev:22.92 (26.8%) stddev-sans-first:23.87 16:46:08 INFO - PROCESS | 1665 | Values: 87.0 84.0 86.0 85.0 134.0 88.0 74.0 127.0 134.0 95.0 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:106.80 Median:92.50 stddev:18.68 (20.2%) stddev-sans-first:19.03 16:46:08 INFO - PROCESS | 1665 | Values: 92.0 93.0 116.0 122.0 93.0 94.0 93.0 109.0 150.0 106.0 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:1.26 (1.5%) stddev-sans-first:1.32 16:46:08 INFO - PROCESS | 1665 | Values: 86.0 85.0 87.0 87.0 84.0 86.0 85.0 83.0 86.0 85.0 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.50 stddev:1.20 (2.2%) stddev-sans-first:1.05 16:46:08 INFO - PROCESS | 1665 | Values: 57.0 55.0 56.0 53.0 56.0 55.0 56.0 54.0 54.0 55.0 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:70.00 stddev:3.43 (4.9%) stddev-sans-first:3.07 16:46:08 INFO - PROCESS | 1665 | Values: 65.0 74.0 69.0 77.0 72.0 69.0 70.0 70.0 69.0 67.0 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:92.90 Median:83.50 stddev:11.07 (13.3%) stddev-sans-first:11.60 16:46:08 INFO - PROCESS | 1665 | Values: 88.0 86.0 84.0 83.0 76.0 100.0 94.0 107.0 105.0 106.0 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:140.70 Median:140.00 stddev:8.87 (6.3%) stddev-sans-first:9.41 16:46:08 INFO - PROCESS | 1665 | Values: 141.0 137.0 140.0 140.0 137.0 140.0 133.0 136.0 165.0 138.0 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:168.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 16:46:08 INFO - PROCESS | 1665 | Values: 168.0 168.0 169.0 167.0 167.0 168.0 168.0 170.0 168.0 167.0 16:46:08 INFO - PROCESS | 1665 | 16:46:08 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:0.70 (1.0%) stddev-sans-first:0.71 16:46:08 INFO - PROCESS | 1665 | Values: 68.0 67.0 68.0 68.0 68.0 67.0 67.0 68.0 67.0 66.0 16:46:08 INFO - PROCESS | 1665 | -------- Summary: end -------- 16:46:08 INFO - PROCESS | 1665 | 16:46:09 INFO - TEST-INFO | 1665: exit 0 16:46:09 INFO - TEST-OK | kraken | took 59779ms 16:46:09 INFO - SUITE-END | took 1177s 16:46:09 INFO - Completed test suite (00:19:37) 16:46:09 INFO - javascript benchmark 16:46:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3339.3154403347307, 3348.9330213395733, 3361.4789707595314, 3368.663133686633, 3398.932021359573, 5632.155176723493, 5688.857778555538, 5718.685472299027, 5782.942170578293, 5803.738831752566, 5691.886162276752, 5725.885482290351, 5826.912596311048, 5835.679037652927, 5844.678542680151, 5120.487951204868, 5188.221766734982, 5206.401263854648, 5234.057869583474, 5259.658122222062, 5311.548518375924, 5318.388385335678, 5318.627696061278, 5332.546733527654, 5334.733263336837, 5131.947950670104, 5289.920651190235, 5303.018941495817, 5334.946650533484, 5367.892642147155, 4442.244818380883, 5188.22176673502, 5213.0, 5221.216817477371, 5245.973770131163, 5182.818601348972, 5199.298094757197, 5209.557187639055, 5250.501202385767, 5282.97358513205, 5744.913826292609, 5753.741081651336, 5776.162456443834, 5795.217645617872, 5796.478316951495, 5225.399079105913, 6095.801078121664, 6121.178620693087, 6269.2476902771505, 6293.433590976788, 6226.2839773426185, 6241.843953901143, 6297.685115744194, 6299.275583307933, 6317.4314311711705, 5847.941520584783, 5852.688188165694, 5867.911981320283, 5879.735411906475, 5920.259967504062, 6145.754169833246, 6191.102290167901, 6199.814005579839, 6231.937680623227, 6298.779542715983, 6153.692315384213, 6199.256089269316, 6208.689565521706, 6216.347283535164, 6220.875582488416, 6259.0, 6270.2789179244055, 6280.246370435577, 6293.96853015732, 6306.148669929501, 6042.214512113397, 6118.314626315956, 6244.937550624527, 6246.875062498815, 6250.9998400255745, 5755.6834374108985, 6133.610107582747, 6219.067139928957, 6252.218472690914, 6279.497640188774, 4922.507749225048, 4928.039032388721, 4931.260310953314, 4933.988532350932, 4953.23224900141, 4434.667399945017, 4469.240229161051, 4493.977530112328, 4502.302143167814, 4534.161180181677, 4865.270209468537, 5717.313922329347, 5720.141978703228, 5757.165211044375, 5816.389279125631, 6291.307956124822, 6311.526635502264, 6322.462590679752, 6339.049142628643, 6342.714577844008, 5794.449527294901, 5836.737346819404, 5837.357890632027, 5850.736716847667, 5897.174395584621, 5275.709835958983, 5370.704611246419, 5403.8108666196495, 5410.702411367334, 5424.972875135678, 6217.564770466114, 6225.719842607003, 6244.000959846403, 6279.246490421178, 6293.559450838397, 6279.780207692709, 6281.780137695158, 6286.685665716787, 6290.842728931813, 6291.245050593958, 5291.796220999749, 6185.474234690102, 6205.689715514116, 6256.562040657111, 6274.811755647338, 6081.966065768742, 6102.489251635809, 6116.853194528166, 6135.233095863017, 6156.230471191101, 6313.179286692701, 6315.968420158054, 6320.936790632035, 6322.083297921868, 6322.841928951813, 6047.5464340173785, 6135.815925522242, 6152.230971128609, 6168.1056246844855, 6168.414000669929, 5923.348431672599, 5973.074173503114, 5983.072623743153, 6135.263768347827, 6151.169592105009, 5110.693358398508, 5960.552958528041, 6003.6397816131175, 6022.0, 6032.517398608013, 6135.815925522063, 6137.508999280136, 6151.784687535915, 6154.292256390393, 6165.84585385351, 5831.35855055952, 5995.070764031582, 6008.188894499187, 6054.66699331541, 6067.54493412987], "value": 5704.647867047846, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.2538878210728, 503.240107437769, 524.1194792748184, 525.8054519827664, 548.4625067433913, 29099.85450072749, 39689.603103968955, 41122.38316425248, 41159.58840411595, 41389.58610413895, 33946.49080263798, 41944.790276048654, 41987.79006104973, 42098.789506052504, 42111.947201319985, 8628.827423451527, 9596.328257022029, 9765.0, 9791.020897910188, 9794.657186998456, 8765.167309105625, 11990.340531270776, 12287.569935052275, 12302.0, 12305.876941230608, 12061.638150855444, 12117.151799374047, 12141.028717702562, 12154.209976351509, 12155.696107597292, 8717.259032982203, 8940.8658870117, 12123.090768192376, 12209.3749843784, 12227.510899564008, 11544.711382215512, 11951.940240298829, 11977.880221197764, 12094.939525302405, 12109.03127749778, 40307.7984610078, 40712.0, 40749.0, 40754.38868416976, 40786.881967705085, 3498.3528047311074, 3723.2925744108534, 3756.9436458453147, 4037.9192416151805, 4132.958670413287, 29787.851060744775, 30874.536881946788, 31246.21884452884, 31320.0, 31373.37253254945, 3111.175538482304, 3248.626407963091, 3266.0365192268337, 3269.967300327014, 3300.1089705779546, 483411.0, 484010.0, 486675.0, 487330.56334718456, 493107.0, 56341.71829140828, 56939.0, 56948.71525642345, 56953.0, 57028.863412512714, 60036.0, 60039.399606004255, 60081.39918600846, 60171.0, 60196.69901650551, 15820.60448488797, 16047.438339658056, 16065.919670401574, 16088.276027578788, 16091.195440228175, 11369.602063927723, 11679.357635329969, 11698.0, 12649.43077561512, 12701.174423662433, 14623.85376146225, 14871.851281487046, 14932.925335373471, 15172.696546069237, 15230.847691522942, 7094.964525177341, 7117.537360071578, 7138.072050633385, 7177.6770045348085, 7215.917612358188, 872.8079822439054, 977.413551868873, 984.640606178754, 1012.8632634594383, 1044.8641676581997, 26127.346816329744, 29173.41653166925, 29528.852355738512, 29553.85223073871, 29706.338784768093, 992.3450522655157, 1024.1857723110145, 1075.763332066944, 1093.3877028863703, 1128.2497139402205, 15046.32291546883, 15165.620859478602, 15174.317155728018, 15257.135734762558, 15492.767608485881, 631383.0, 635973.0, 636958.0, 637790.0, 639225.0, 56822.43177568254, 56867.57831054174, 56900.0, 56955.14567281411, 56956.14565781516, 1236.1347057059913, 1274.7832868412206, 1281.3529167770216, 1384.3216823756286, 1407.3315175291655, 26224.60663090017, 28381.858090709415, 30020.69979300179, 31256.749730011456, 31938.36123277568, 50959.74520127524, 51607.48392516177, 51660.741696291276, 51663.48336516587, 51767.0, 582521.0, 583999.0, 584508.0, 587153.0, 588785.5139556105, 22677.86610669427, 23269.534609307382, 25030.249092527254, 25587.0, 37909.81045094757, 46112.53887461082, 46149.538504614524, 46972.7651361741, 47027.589172324886, 49987.75006124946, 31469.8426507866, 37423.812880935424, 38287.80856095702, 39011.6098839008, 39392.0, 37553.81223093867, 38031.429528557645, 38697.61302387053, 39177.6082239174, 39409.40885886657, 37766.57232829713, 37876.863694089225, 38550.0, 38686.22627547377, 38760.22479550337, 32862.68549258003, 33323.1669208262, 34153.316933661685, 34421.48367774535, 34495.482567761, 24748.50502989894, 24856.627150592394, 25036.874815625804, 25241.621375679013, 25539.9784008645], "value": 20122.87108832104, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.8846624846925, 370.50167524679296, 373.89157144428117, 381.6317253850034, 403.06891081601503, 15226.619334516628, 15491.380344786208, 17106.40127595533, 20691.068901899413, 21304.786952130475, 19805.603887922232, 20319.0, 20436.080376383063, 20629.0, 21486.033128509214, 4789.401324834387, 5766.769329226847, 6102.786402475915, 6138.570300078996, 6174.660393678346, 5953.464188223059, 6089.817305480843, 6092.238470191226, 6093.421124993119, 6104.053871649901, 3869.361555343365, 4082.122343696105, 6316.936830631681, 6637.568558043736, 6676.3657452542175, 5970.0, 6061.090836374535, 6722.663866806664, 6728.461723062168, 6733.8989915151315, 5888.764449422061, 5903.0, 5908.172855800191, 5909.349971503174, 5913.9112913306335, 13279.800802987962, 17385.565360865952, 25499.617505737428, 26178.34554136143, 26466.470670586674, 2180.967285490719, 2187.7484089329773, 2195.132922495621, 2293.942651433711, 2799.664040315155, 9831.115199631997, 9898.257630677728, 9910.306278560503, 10412.11497022754, 10422.84365734507, 1798.6672465593906, 1851.8148185181508, 1870.4014715291112, 1885.981140188608, 1946.4744518979937, 113570.0, 116710.24934625988, 117320.41339793331, 117853.0, 118803.13865713202, 11156.442177891073, 11202.831957520644, 11357.829632555518, 11420.143489238175, 11572.76854462906, 14311.713765724628, 14335.068220565838, 14346.426142954166, 14347.35436905321, 14359.85640143606, 8283.337333013465, 8405.873911891327, 8411.537365444838, 8415.452995555392, 8549.572521373906, 8399.674167803587, 8403.604972582785, 8422.0, 8554.315654747605, 8591.914080859111, 4452.799624016927, 4474.485434175112, 4474.843380481732, 4489.461264648263, 4513.029698614813, 1837.4579499047813, 1879.4267748336888, 1882.5481884347657, 1883.9717404238947, 1888.5835623282537, 363.914480097177, 395.39109770952984, 870.7823044238941, 937.6483818568037, 960.2619259140977, 9363.672271470467, 10983.61557345505, 11988.220765650205, 12002.039836813034, 12027.458764355624, 414.7034870067916, 425.4511679932852, 426.27533193571054, 437.04069567299473, 445.0431572119903, 5080.161773307363, 5630.690312032795, 5909.940900590939, 6047.15339852421, 6075.635461872302, 566150.0, 615701.0, 616020.0, 624943.8752806297, 634105.0, 87998.0, 105820.47089764656, 108524.4573777126, 132479.0, 138001.999999998, 437.44226111707826, 438.7871882137161, 447.47198305999234, 449.16679559417463, 450.6822690003605, 11003.064739497233, 11185.272957257752, 11406.543738250377, 11441.370724610226, 11533.365664888512, 51620.0, 53651.46348536621, 55153.72423137858, 55359.0, 55384.44615553793, 79146.0, 82145.0, 82879.17120828714, 84525.0, 85205.0, 7673.155952845071, 7778.844423111619, 7957.244061814118, 8119.512829230265, 8275.420720549504, 9920.503974801375, 11717.472713727731, 12426.316552589695, 12557.623271301876, 12796.808047879476, 11271.718207044889, 11296.49165787525, 11415.086793056702, 11446.084313255085, 11473.50844390224, 11110.277831940897, 11173.608923687632, 11421.257618254787, 11440.48517816717, 11603.709907252049, 11337.0, 11367.261128026983, 11727.41362931833, 11791.705207369885, 11825.704357391134, 6919.827004324932, 7735.458517903693, 7866.488678235896, 8101.068377136705, 8304.086550479322, 10476.528556214836, 11075.889241107487, 11285.830712539482, 11287.492062857027, 11462.541498339973], "value": 8595.682713706265, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1372.5607805502243, 1537.3389442539708, 1545.7604071368937, 1748.9650206995861, 1893.867429279951, 7791.181925897777, 8252.546109963949, 8533.743987680364, 8788.604512796923, 8824.249938755198, 8639.697610583615, 8727.607257673404, 8730.0, 8735.91264087359, 8751.0, 2331.731850837154, 2363.7872591466767, 2367.1241640593003, 2367.6566897799894, 2369.123424332999, 2070.81362677359, 2324.8256380771427, 2331.6036273833406, 2346.4485845826216, 2373.92878213653, 2339.9298021059394, 2359.5634807560655, 2361.669366288721, 2373.7151541815006, 2386.474975505386, 2309.411100169446, 2339.543788961153, 2348.201611452106, 2352.8353015288935, 2361.2325994052003, 2332.6734257204007, 2336.5210131922913, 2337.8012868905994, 2340.379799353173, 2342.3792694935855, 8619.956900215459, 8792.560371981375, 8804.987426445976, 8807.64769409223, 8823.029466758651, 5143.3570803649545, 5153.381594208681, 5300.284461597674, 5302.814401495929, 5331.413544510139, 3433.141714571357, 3569.161247106928, 3647.689946354563, 3655.232401195752, 3664.9816750916075, 1070.336391437306, 1073.785242951405, 1075.317173594765, 1094.3816743539874, 1095.901368876797, 11523.654290371385, 11569.942150289195, 11683.123765717604, 11695.181337306392, 11730.002949749281, 4385.0, 4405.163019026375, 4565.452145742533, 4588.082383523242, 4593.816247350135, 9139.680111196076, 9147.862782058275, 9150.039245879087, 9160.175584197454, 9160.725178244664, 5199.506046925536, 5203.687778733288, 5207.973960130175, 5212.218167274939, 5214.618126196561, 4152.231837110145, 4395.890102747457, 4545.113702827917, 4567.0, 4873.439554451284, 3680.3191409589313, 3695.6858667013066, 3762.9623703762613, 3769.359208934488, 3770.490983717218, 974.7319487141093, 1129.1192869561755, 1138.8348689439986, 1139.1114930354338, 1147.713071732067, 684.7226873116396, 868.4962721621445, 895.4985208283382, 900.2752784008935, 949.0272470717432, 4685.344051832746, 4955.677880937727, 5632.577556683265, 5790.536757059425, 5919.0, 889.2219308105408, 901.0538934119148, 911.2209061252667, 920.0569416348296, 946.7065209784988, 3312.0, 3751.8874433767032, 3820.369639009588, 3849.826757795906, 3938.6849052075763, 10427.739306517398, 10863.51114199863, 10873.021428071512, 10878.184136189817, 10878.238523303451, 10830.296030757976, 10839.295445796155, 10849.457527123612, 10883.891161088446, 10892.237543372046, 8032.0361556612415, 8335.291265290602, 8340.332773378115, 8347.29048030912, 8364.623591938378, 4854.5873600744335, 5005.299258103942, 5024.623153263593, 5835.570285280133, 5869.941300586939, 9410.153086222273, 9634.470104144058, 9665.806683866143, 9745.902540974783, 10023.148032417326, 9386.0, 9836.754081148028, 10112.241581881535, 10203.132733717717, 10255.33340332876, 6996.370326670624, 7192.280771922766, 7347.412207023533, 7561.621918903923, 7588.506747061423, 8207.097219305751, 8282.834343313221, 8324.542150181798, 8382.622781974947, 8407.11725268838, 6903.344182302673, 7148.606826624586, 7600.657970391456, 7647.38820894316, 7668.424868134801, 7305.452091093083, 7377.0, 7451.701931922662, 7454.813629659085, 7572.20491848348, 6800.489963252875, 6985.790426287016, 7079.858402831812, 7084.61034643079, 7086.751963681247, 4883.096627123993, 4956.256561515802, 4964.95735895466, 5012.498750124958, 5153.278541004187, 4226.393970291392, 4322.265214913535, 4338.566143385636, 4342.2835232186335, 4431.800568974469], "value": 4479.981966172764, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.5357196345664, 300.3077905427988, 309.865208634244, 314.763927054709, 316.5584010305622, 5065.898682026357, 5218.843434696955, 5268.894622107556, 5324.440933701963, 5378.784848606051, 4879.024195160973, 5077.227406238134, 5169.844904652857, 5188.0, 5263.315708988424, 3343.328335832084, 4096.528899176603, 4121.505419349667, 4121.732087414323, 4144.274751918402, 3966.52401711795, 4084.550699423076, 4088.6524645405175, 4129.525104612963, 4145.917081658365, 4024.5170579530495, 4027.2750904837117, 4046.736962097469, 4078.490188726409, 4086.0, 4036.919261614751, 4050.5341885683083, 4053.8378464861516, 4080.2655522006025, 4082.183563287325, 3327.5175099610665, 3395.2700169463437, 3544.8759293424605, 3564.322778672044, 3571.7320351275184, 3878.4376265441633, 4822.373091498118, 4866.635002374836, 5157.381114266274, 5184.455632158607, 3868.9881261744017, 4654.464736555272, 4657.0, 4687.624990000826, 4692.756419548822, 4938.08645400602, 5273.44042771128, 5582.302212223472, 5596.972015139939, 5597.44025597437, 3573.9456860226296, 3657.817109144559, 3776.6412190841825, 3929.4302326162547, 3933.468981687493, 5310.44240354761, 5812.215350927613, 5820.388859169769, 5832.591718579701, 5844.772597754477, 3170.3500782339565, 3412.2663627320244, 3672.6510981456718, 4008.6392224699916, 4010.8997275067773, 3582.390993531106, 3607.5851277102943, 4040.4141399496907, 4073.348264277701, 4092.8485066499225, 4419.580139886705, 4465.665075119314, 4663.603593694574, 4666.463356714022, 4682.4381074271305, 4680.555347242007, 5626.690532020696, 5637.379888212294, 5666.231955749463, 5680.715964201773, 1277.163457935054, 1296.3647812571771, 1331.9943442700699, 1332.3404914567352, 1369.4795977528474, 887.8901373283396, 909.7816524034184, 912.9406588571721, 949.0556895888498, 950.2825366848124, 4569.634429245652, 4821.662483626113, 5160.458151894072, 5225.764840582183, 5234.450382709837, 5284.445133260952, 5436.456354364532, 5672.602917795797, 5731.369549349568, 5746.597738158289, 5100.0, 5125.948740512622, 5128.666636668605, 5141.0232055909255, 5189.844304670791, 2075.107703687429, 2296.81625469962, 2440.133752517866, 2451.33813870254, 2483.888225029878, 4236.406903033578, 4813.518648135158, 4957.429895562056, 5421.295231619864, 6013.0, 5534.446555344514, 6015.639061656315, 6026.9095963560585, 6049.939500605025, 6070.6357618543025, 5264.605154613343, 5881.617694849821, 6037.54718396122, 6065.878682426327, 6104.755809767559, 4830.154722923404, 4878.438979517259, 5482.835514934558, 5581.972090139523, 5822.796202132905, 4962.156433406401, 5052.494750524918, 5292.338457692788, 5774.307083150049, 5794.159846822272, 5670.659760414388, 5693.772249110155, 5928.525717942468, 6034.366391528826, 6053.697315134314, 3568.232829941575, 3583.7849729016343, 3681.5457894132205, 3894.7857867817543, 3913.784741839226, 5092.7777333440445, 5625.268715067015, 5845.707714614167, 5855.970720146543, 5895.351511333664, 4667.346571480063, 4799.0, 5390.110631745718, 5454.809081682122, 5674.06377947634, 4666.8133274668635, 5653.745581448762, 5661.490465858093, 5668.291463567054, 5675.517581005495, 4546.74992875395, 5136.1268584340005, 5273.525382715574, 5415.566754659539, 5555.722213889208, 5699.523290792696, 5711.571632127524, 5717.771289148387, 5728.656280623176, 5742.827715168552, 4489.653104068831, 5261.5264626183825, 5444.455554444682, 5543.77824887, 5556.388797232382], "value": 4056.958238435592, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.8215446138464, 767.5318055985848, 786.3787607789847, 820.9425340226179, 842.4355681693265, 13693.726125477484, 14614.48849290275, 14826.851731482682, 15601.609959751011, 15928.76106858398, 14133.787993180085, 14274.429022839075, 14342.92828535856, 14370.353334099964, 14382.640433989129, 831.7296878514477, 862.9482231066124, 863.386995233385, 863.4689665855485, 865.7878819689184, 691.4399336537396, 712.7149140343852, 715.9713611455536, 853.6372041882206, 877.5831480046991, 919.6689191890914, 921.4195057114009, 922.0226559846584, 923.4966943016072, 925.453982150531, 915.3775432705758, 917.6788124156559, 919.3242966419709, 920.1764420843341, 922.4327038871113, 536.1448489658987, 551.242042191986, 584.2667452347278, 655.4003087175229, 675.3955210086993, 14832.851671483362, 15220.086794792238, 15229.847701522955, 15236.162011089385, 15250.932434729573, 13733.0, 14131.0, 14137.363818628186, 14190.148591084568, 14381.0, 12852.293123878182, 12876.163049401759, 13059.347032648424, 13312.800307995389, 13558.186508809504, 4608.5621865922685, 4644.4658864230705, 4662.3239630253765, 4845.685030473008, 4850.587700045465, 116884.0, 119056.40471797585, 120157.0, 120857.39571302263, 122162.0, 11141.72145696364, 11239.100871930226, 11277.943610281896, 11508.0, 11657.417129143509, 22605.80066584662, 22621.20825771123, 22646.0, 22678.0, 22704.31887043391, 7968.442209045311, 8090.4336696431865, 8122.390820688472, 8138.674453021931, 8420.705275315335, 4471.910561788811, 4490.236659767847, 4560.931586026212, 4667.276572131325, 4698.295255711602, 3313.950290745641, 3447.172678557178, 3451.0854623524788, 3453.2230248193955, 3459.8270086495572, 627.4321738826369, 680.4726337088796, 694.5034300475185, 707.7699747582056, 735.5219107580116, 571.7284289962317, 591.6479694581717, 626.0859145647315, 686.4165459359505, 686.8694947959972, 9422.858657120149, 9636.75908102303, 9744.951275243578, 9872.457014864107, 9883.950580247052, 653.5882394091786, 668.5086461450829, 677.9559328643622, 691.2534462780185, 699.7655785311875, 4500.639948804088, 4751.858449351679, 4764.737939413366, 4787.952120478821, 4796.932816191858, 152302.0, 155905.0, 158903.0, 160524.39475605328, 162955.0, 134242.0, 135413.01098329775, 135772.32113839567, 138587.0, 141816.0, 116553.41723291499, 116779.0, 116815.0, 117862.99999999828, 120380.0, 9162.633494660138, 9184.311176661644, 9186.265098791948, 9209.079092090737, 9251.306152038489, 54982.72508637431, 62874.0, 65340.0, 65793.34206657873, 67113.66443167753, 96589.03410965801, 97187.0, 97665.0, 98403.0, 98759.50620246853, 12160.0, 12241.938790305992, 12313.56902508408, 12338.629841104781, 12859.0998630095, 11624.767504650028, 13562.79655805144, 14752.188629625474, 14968.625784355478, 15322.616934576725, 12195.451204695633, 13407.128536645087, 14200.644983875485, 14622.903282254087, 14631.0, 11781.52873885035, 13991.650208744455, 14176.291185440892, 14227.288635567973, 14371.20958347301, 13427.462901483832, 13701.725965480435, 13851.376688049264, 14072.155670659384, 14307.070040447337, 11325.15061370417, 11413.0, 11489.368084755419, 11499.885001150216, 11711.414429278331, 6460.717580472125, 9423.340366174301, 10304.433256170683, 10488.790224195625, 10896.291741036806], "value": 7976.976813151161, "unit": "score"}], "name": "dromaeo_css", "value": 7232.028141433684, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 117.0, 132.0, 114.0, 114.0, 113.0, 114.0, 126.0, 167.0], "value": 122.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 109.0, 144.0, 109.0, 113.0, 131.0, 140.0, 146.0, 108.0], "value": 125.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 184.0, 184.0, 183.0, 181.0, 184.0, 179.0, 194.0, 261.0], "value": 191.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 60.0, 88.0, 61.0, 61.0, 59.0, 60.0, 91.0, 59.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 94.0, 114.0, 70.0, 70.0, 70.0, 119.0, 98.0, 72.0, 89.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 85.0, 134.0, 88.0, 74.0, 127.0, 134.0, 95.0], "value": 99.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 116.0, 122.0, 93.0, 94.0, 93.0, 109.0, 150.0, 106.0], "value": 106.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 87.0, 84.0, 86.0, 85.0, 83.0, 86.0, 85.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 56.0, 53.0, 56.0, 55.0, 56.0, 54.0, 54.0, 55.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 69.0, 77.0, 72.0, 69.0, 70.0, 70.0, 69.0, 67.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 84.0, 83.0, 76.0, 100.0, 94.0, 107.0, 105.0, 106.0], "value": 92.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 137.0, 140.0, 140.0, 137.0, 140.0, 133.0, 136.0, 165.0, 138.0], "value": 140.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 169.0, 167.0, 167.0, 168.0, 168.0, 170.0, 168.0, 167.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 68.0, 68.0, 67.0, 67.0, 68.0, 67.0, 66.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1481.5000000000002, "alertThreshold": 2.0}]} 16:46:09 INFO - Return code: 0 16:46:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:46:09 INFO - # TBPL SUCCESS # 16:46:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:46:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:46:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:46:09 INFO - 2016-09-28 16:46:09.929 screenresolution[1669:9696] starting screenresolution argv=screenresolution get 16:46:09 INFO - 2016-09-28 16:46:09.932 screenresolution[1669:9696] Display 0: 1600x1200x32@60 16:46:09 INFO - 2016-09-28 16:46:09.938 screenresolution[1670:9700] starting screenresolution argv=screenresolution list 16:46:09 INFO - Available Modes on Display 0 16:46:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:46:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:46:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:46:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:46:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:46:10 INFO - Intel Iris: 16:46:10 INFO - Chipset Model: Intel Iris 16:46:10 INFO - Type: GPU 16:46:10 INFO - Bus: Built-In 16:46:10 INFO - VRAM (Dynamic, Max): 1536 MB 16:46:10 INFO - Vendor: Intel (0x8086) 16:46:10 INFO - Device ID: 0x0a2e 16:46:10 INFO - Revision ID: 0x0009 16:46:10 INFO - Displays: 16:46:10 INFO - Display: 16:46:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:46:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:46:10 INFO - Display Serial Number: 1600x1200 60 16:46:10 INFO - Main Display: Yes 16:46:10 INFO - Mirror: Off 16:46:10 INFO - Online: Yes 16:46:10 INFO - Rotation: Supported 16:46:10 INFO - Return code: 0 16:46:10 INFO - Running post-action listener: _resource_record_post_action 16:46:10 INFO - [mozharness: 2016-09-28 23:46:10.174850Z] Finished run-tests step (success) 16:46:10 INFO - Running post-run listener: _resource_record_post_run 16:46:10 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37344256; Write bytes: 706980352; Read time: 252; Write time: 1941 16:46:10 INFO - TinderboxPrint: CPU usage
53.7% 16:46:10 INFO - TinderboxPrint: I/O read bytes / time
37,344,256 / 252 16:46:10 INFO - TinderboxPrint: I/O write bytes / time
706,980,352 / 1,941 16:46:10 INFO - TinderboxPrint: CPU idle
2,247.3 (46.3%) 16:46:10 INFO - TinderboxPrint: CPU system
971.9 (20.0%) 16:46:10 INFO - TinderboxPrint: CPU user
1,630.5 (33.6%) 16:46:10 INFO - TinderboxPrint: Swap in / out
444,456,960 / 0 16:46:10 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404140544; Write bytes: 397133824; Read time: 29872; Write time: 792 16:46:10 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35226624; Write bytes: 294875648; Read time: 232; Write time: 1106 16:46:10 INFO - Running post-run listener: _upload_blobber_files 16:46:10 INFO - Blob upload gear active. 16:46:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:46:10 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:46:10 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:46:10 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:46:11 INFO - (blobuploader) - INFO - Open directory for files ... 16:46:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:46:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:46:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:46:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:46:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:46:12 INFO - (blobuploader) - INFO - Done attempting. 16:46:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:46:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:46:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:46:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:46:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:46:13 INFO - (blobuploader) - INFO - Done attempting. 16:46:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:46:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:46:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:46:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:46:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:46:16 INFO - (blobuploader) - INFO - Done attempting. 16:46:16 INFO - (blobuploader) - INFO - Iteration through files over. 16:46:16 INFO - Return code: 0 16:46:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:46:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:46:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1c858a46d1cd0d34e2a43178788d0d188b5898fc47653c691dc8ff0ffe41b0b0ef5981e28e1998b270fe1698bc5cf3ea252114dff2ee33eff3acc563727674f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/45b1a237743d1bfa1c5312323b30a035a2a90742556495bf25cb61688a0c054beb6c1500aa212a901905d6689432cc87567c8624234af03bc90dbb04df0b3bba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9611023ff833abf0d5d34b7e4d85365adb9c89867c7a3f7177c0c366739f99b5b0fad5f3cae10157cb7a0506367f7df1e175a898b64b469b7876e990f7b93308"} 16:46:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:46:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:46:16 INFO - Contents: 16:46:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1c858a46d1cd0d34e2a43178788d0d188b5898fc47653c691dc8ff0ffe41b0b0ef5981e28e1998b270fe1698bc5cf3ea252114dff2ee33eff3acc563727674f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/45b1a237743d1bfa1c5312323b30a035a2a90742556495bf25cb61688a0c054beb6c1500aa212a901905d6689432cc87567c8624234af03bc90dbb04df0b3bba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9611023ff833abf0d5d34b7e4d85365adb9c89867c7a3f7177c0c366739f99b5b0fad5f3cae10157cb7a0506367f7df1e175a898b64b469b7876e990f7b93308"} 16:46:16 INFO - Running post-run listener: copy_logs_to_upload_dir 16:46:16 INFO - Copying logs to upload dir... 16:46:16 INFO - mkdir: /builds/slave/test/build/upload/logs 16:46:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.731353 ========= master_lag: 0.22 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-28 16:46:16.638961) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 16:46:16.642214) ========= 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.aQe2DOv3e7/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.EziylGosw2/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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/Autoland/sha512/1c858a46d1cd0d34e2a43178788d0d188b5898fc47653c691dc8ff0ffe41b0b0ef5981e28e1998b270fe1698bc5cf3ea252114dff2ee33eff3acc563727674f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/45b1a237743d1bfa1c5312323b30a035a2a90742556495bf25cb61688a0c054beb6c1500aa212a901905d6689432cc87567c8624234af03bc90dbb04df0b3bba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9611023ff833abf0d5d34b7e4d85365adb9c89867c7a3f7177c0c366739f99b5b0fad5f3cae10157cb7a0506367f7df1e175a898b64b469b7876e990f7b93308"} build_url:https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011268 build_url: 'https://queue.taskcluster.net/v1/task/b8VGLzrDTBadXP8_t6B91g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1c858a46d1cd0d34e2a43178788d0d188b5898fc47653c691dc8ff0ffe41b0b0ef5981e28e1998b270fe1698bc5cf3ea252114dff2ee33eff3acc563727674f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/45b1a237743d1bfa1c5312323b30a035a2a90742556495bf25cb61688a0c054beb6c1500aa212a901905d6689432cc87567c8624234af03bc90dbb04df0b3bba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9611023ff833abf0d5d34b7e4d85365adb9c89867c7a3f7177c0c366739f99b5b0fad5f3cae10157cb7a0506367f7df1e175a898b64b469b7876e990f7b93308"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 16:46:16.679476) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 16:46:16.679812) ========= 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.aQe2DOv3e7/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.EziylGosw2/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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.004968 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 16:46:16.728645) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 16:46:16.728968) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 16:46:16.729290) ========= ========= Total master_lag: 0.65 =========