builder: graphics_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0119 starttime: 1479804137.98 results: success (0) buildid: 20161121214150 builduid: 856ae13c760b4f009905c30385a86ff8 revision: 805d42464705b0c6d7db1101c1550dc4855b4329 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:17.985329) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:17.985821) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:17.986112) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:18.013123) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:18.013551) ========= 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.Wmgagls9MT/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.gnj0n6JnkI/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-22 00:42:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 53.3M=0s 2016-11-22 00:42:18 (53.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091903 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:18.130288) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:18.130621) ========= 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.Wmgagls9MT/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.gnj0n6JnkI/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.026733 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:18.180923) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:18.181317) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 805d42464705b0c6d7db1101c1550dc4855b4329 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 805d42464705b0c6d7db1101c1550dc4855b4329 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Wmgagls9MT/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.gnj0n6JnkI/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-22 00:42:18,273 truncating revision to first 12 chars 2016-11-22 00:42:18,274 Setting DEBUG logging. 2016-11-22 00:42:18,274 attempt 1/10 2016-11-22 00:42:18,274 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/805d42464705?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 00:42:18,617 unpacking tar archive at: graphics-805d42464705/testing/mozharness/ program finished with exit code 0 elapsedTime=0.597416 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:18.802379) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:18.802702) ========= script_repo_revision: 805d42464705b0c6d7db1101c1550dc4855b4329 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:18.803108) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:18.803408) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:18.823330) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-22 00:42:18.823697) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Wmgagls9MT/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.gnj0n6JnkI/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 00:42:19 INFO - MultiFileLogger online at 20161122 00:42:19 in /builds/slave/test 00:42:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 00:42:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:42:19 INFO - {'append_to_log': False, 00:42:19 INFO - 'base_work_dir': '/builds/slave/test', 00:42:19 INFO - 'blob_upload_branch': 'Graphics', 00:42:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:42:19 INFO - 'branch': 'Graphics', 00:42:19 INFO - 'buildbot_json_path': 'buildprops.json', 00:42:19 INFO - 'config_files': ('talos/mac_config.py',), 00:42:19 INFO - 'default_actions': ('clobber', 00:42:19 INFO - 'read-buildbot-config', 00:42:19 INFO - 'download-and-extract', 00:42:19 INFO - 'populate-webroot', 00:42:19 INFO - 'create-virtualenv', 00:42:19 INFO - 'install', 00:42:19 INFO - 'run-tests'), 00:42:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:42:19 INFO - 'download_minidump_stackwalk': True, 00:42:19 INFO - 'download_symbols': 'ondemand', 00:42:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:42:19 INFO - 'tooltool.py': '/tools/tooltool.py', 00:42:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:42:19 INFO - '/tools/misc-python/virtualenv.py')}, 00:42:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:42:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:42:19 INFO - 'installer_path': 'installer.exe', 00:42:19 INFO - 'log_level': 'info', 00:42:19 INFO - 'log_name': 'talos', 00:42:19 INFO - 'log_to_console': True, 00:42:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:42:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:42:19 INFO - 'opt_config_files': (), 00:42:19 INFO - 'pip_index': False, 00:42:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:42:19 INFO - 'cmd': ('bash', 00:42:19 INFO - '-c', 00:42:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:42:19 INFO - 'enabled': True, 00:42:19 INFO - 'halt_on_failure': False, 00:42:19 INFO - 'name': 'check_screen_resolution'},), 00:42:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:42:19 INFO - 'cmd': ('bash', 00:42:19 INFO - '-c', 00:42:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:42:19 INFO - 'enabled': True, 00:42:19 INFO - 'halt_on_failure': False, 00:42:19 INFO - 'name': 'check_screen_resolution'},), 00:42:19 INFO - 'run_cmd_checks_enabled': True, 00:42:19 INFO - 'sps_profile': False, 00:42:19 INFO - 'sps_profile_interval': 0, 00:42:19 INFO - 'suite': 'dromaeojs', 00:42:19 INFO - 'system_bits': '32', 00:42:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:42:19 INFO - 'title': 't-yosemite-r7-0119', 00:42:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:42:19 INFO - 'use_talos_json': True, 00:42:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:42:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:42:19 INFO - 'work_dir': 'build'} 00:42:19 INFO - [mozharness: 2016-11-22 08:42:19.029082Z] Running clobber step. 00:42:19 INFO - Running pre-action listener: _resource_record_pre_action 00:42:19 INFO - Running main action method: clobber 00:42:19 INFO - rmtree: /builds/slave/test/build 00:42:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:42:21 INFO - Running post-action listener: _resource_record_post_action 00:42:21 INFO - [mozharness: 2016-11-22 08:42:21.511088Z] Finished clobber step (success) 00:42:21 INFO - [mozharness: 2016-11-22 08:42:21.511340Z] Running read-buildbot-config step. 00:42:21 INFO - Running pre-action listener: _resource_record_pre_action 00:42:21 INFO - Running main action method: read_buildbot_config 00:42:21 INFO - Using buildbot properties: 00:42:21 INFO - { 00:42:21 INFO - "project": "", 00:42:21 INFO - "product": "firefox", 00:42:21 INFO - "script_repo_revision": "default", 00:42:21 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs", 00:42:21 INFO - "repository": "", 00:42:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs", 00:42:21 INFO - "buildid": "20161121214150", 00:42:21 INFO - "pgo_build": "False", 00:42:21 INFO - "basedir": "/builds/slave/test", 00:42:21 INFO - "buildnumber": 2, 00:42:21 INFO - "slavename": "t-yosemite-r7-0119", 00:42:21 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 00:42:21 INFO - "platform": "yosemite_r7", 00:42:21 INFO - "scheduler": "tests-graphics-macosx64-talos", 00:42:21 INFO - "branch": "graphics", 00:42:21 INFO - "repo_path": "projects/graphics", 00:42:21 INFO - "revision": "805d42464705b0c6d7db1101c1550dc4855b4329", 00:42:21 INFO - "stage_platform": "macosx64", 00:42:21 INFO - "builduid": "856ae13c760b4f009905c30385a86ff8", 00:42:21 INFO - "slavebuilddir": "test" 00:42:21 INFO - } 00:42:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 00:42:21 INFO - Running post-action listener: _resource_record_post_action 00:42:21 INFO - [mozharness: 2016-11-22 08:42:21.517065Z] Finished read-buildbot-config step (success) 00:42:21 INFO - [mozharness: 2016-11-22 08:42:21.517278Z] Running download-and-extract step. 00:42:21 INFO - Running pre-action listener: _resource_record_pre_action 00:42:21 INFO - Running main action method: download_and_extract 00:42:21 INFO - mkdir: /builds/slave/test/build/tests 00:42: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')]} 00:42:21 INFO - trying https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 00:42:21 INFO - Downloading https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 00:42:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 00:42:22 INFO - Downloaded 1199 bytes. 00:42:22 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 00:42:22 INFO - Using the following test package requirements: 00:42:22 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 00:42:22 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:22 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 00:42:22 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:22 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 00:42:22 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:22 INFO - u'jsshell-mac.zip'], 00:42:22 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:22 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 00:42:22 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 00:42:22 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:22 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 00:42:22 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:22 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 00:42:22 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:22 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 00:42:22 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:22 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 00:42:22 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 00:42:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 00:42:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 00:42:22 INFO - Fetch https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 00:42:23 INFO - Http code: 200 00:42:23 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 00:42:23 INFO - x-amz-cf-id: P79dI0JhPkGiVAku_XNwFPR3GWcDm1q1LHfcpCsNuR8Sv4q90BAwiQ== 00:42:23 INFO - x-amz-version-id: cyjw8R_e_8uORi7EIXO.qzqiEgwKD2N5 00:42:23 INFO - Expected file size: 16282111 00:42:23 INFO - Obtained file size: 16282111 00:42:29 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 00:42:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 00:42:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 00:42:29 INFO - Fetch https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 00:42:30 INFO - Http code: 200 00:42:30 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 00:42:30 INFO - x-amz-cf-id: 2d18ZRMML48hpGB9-jPebdIgUMi3NA6b9CTaoYifs7nD9dP9_sy0DA== 00:42:30 INFO - x-amz-version-id: cyjw8R_e_8uORi7EIXO.qzqiEgwKD2N5 00:42:30 INFO - Expected file size: 16282111 00:42:30 INFO - Obtained file size: 16282111 00:42:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 00:42:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 00:42:37 INFO - Fetch https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 00:42:37 INFO - Http code: 200 00:42:37 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 00:42:37 INFO - x-amz-cf-id: MmDSckECJqs7LBMXPD26G6-nACNoUOjO1_HwpK2NNfkJqBMuWaCFvg== 00:42:37 INFO - x-amz-version-id: UbfA0rJLcAl1iQRs8xJeE8REvrPkJFdg 00:42:38 INFO - Expected file size: 14409392 00:42:38 INFO - Obtained file size: 14409392 00:42:38 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')]} 00:42:38 INFO - trying https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 00:42:38 INFO - Downloading https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 00:42:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 00:42:41 INFO - Downloaded 107571587 bytes. 00:42:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 00:42:41 INFO - mkdir: /builds/slave/test/properties 00:42:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:42:41 INFO - Writing to file /builds/slave/test/properties/build_url 00:42:41 INFO - Contents: 00:42:41 INFO - build_url:https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 00:42:41 INFO - Running post-action listener: _resource_record_post_action 00:42:41 INFO - Running post-action listener: set_extra_try_arguments 00:42:41 INFO - [mozharness: 2016-11-22 08:42:41.980508Z] Finished download-and-extract step (success) 00:42:41 INFO - [mozharness: 2016-11-22 08:42:41.980641Z] Running populate-webroot step. 00:42:41 INFO - Running pre-action listener: _resource_record_pre_action 00:42:41 INFO - Running main action method: populate_webroot 00:42:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:42:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:42:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:42:41 INFO - '--tppagecycles', 00:42:41 INFO - '7'], 00:42:41 INFO - 'tests': ['tsvgm']}}, 00:42:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:42:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:42:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:42:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:42:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:42:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:42:41 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 00:42:41 INFO - 'talos_options': ['--disable-e10s'], 00:42:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:42:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 00:42:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:42:41 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 00:42:41 INFO - 'talos_options': ['--disable-e10s'], 00:42:41 INFO - 'tests': ['damp', 'tps']}, 00:42:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 00:42:41 INFO - 'tests': ['damp', 'tps']}, 00:42:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:42:41 INFO - 'tests': ['dromaeo_dom']}, 00:42:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:42:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:42:41 INFO - 'tests': ['basic_compositor_video']}, 00:42:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:42:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:42:41 INFO - 'tests': ['a11yr', 00:42:41 INFO - 'ts_paint', 00:42:41 INFO - 'tpaint', 00:42:41 INFO - 'sessionrestore', 00:42:41 INFO - 'sessionrestore_no_auto_restore', 00:42:41 INFO - 'tabpaint']}, 00:42:41 INFO - 'other-e10s': {'tests': ['a11yr', 00:42:41 INFO - 'ts_paint', 00:42:41 INFO - 'tpaint', 00:42:41 INFO - 'sessionrestore', 00:42:41 INFO - 'sessionrestore_no_auto_restore', 00:42:41 INFO - 'tabpaint']}, 00:42:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:42:41 INFO - 'ts_paint', 00:42:41 INFO - 'tpaint', 00:42:41 INFO - 'sessionrestore', 00:42:41 INFO - 'sessionrestore_no_auto_restore', 00:42:41 INFO - 'tabpaint']}, 00:42:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:42:41 INFO - 'ts_paint', 00:42:41 INFO - 'tpaint', 00:42:41 INFO - 'sessionrestore', 00:42:41 INFO - 'sessionrestore_no_auto_restore', 00:42:41 INFO - 'tabpaint']}, 00:42:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:42:41 INFO - 'tests': ['a11yr', 00:42:41 INFO - 'ts_paint', 00:42:41 INFO - 'tpaint', 00:42:41 INFO - 'sessionrestore', 00:42:41 INFO - 'sessionrestore_no_auto_restore', 00:42:41 INFO - 'tabpaint']}, 00:42:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:42:41 INFO - 'tests': ['a11yr', 00:42:41 INFO - 'ts_paint', 00:42:41 INFO - 'tpaint', 00:42:41 INFO - 'sessionrestore', 00:42:41 INFO - 'sessionrestore_no_auto_restore', 00:42:41 INFO - 'tabpaint']}, 00:42:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:42:41 INFO - 'tests': ['tsvgx', 00:42:41 INFO - 'tsvgr_opacity', 00:42:41 INFO - 'tart', 00:42:41 INFO - 'tscrollx', 00:42:41 INFO - 'cart']}, 00:42:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:42:41 INFO - 'tsvgr_opacity', 00:42:41 INFO - 'tart', 00:42:41 INFO - 'tscrollx', 00:42:41 INFO - 'cart']}, 00:42:41 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 00:42:41 INFO - 'talos_options': ['--disable-e10s'], 00:42:41 INFO - 'tests': ['tp5o']}, 00:42:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 00:42:41 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 00:42:41 INFO - 'talos_options': ['--disable-e10s', 00:42:41 INFO - '--xperf_path', 00:42:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:42:41 INFO - 'tests': ['tp5n']}, 00:42:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 00:42:41 INFO - 'talos_options': ['--xperf_path', 00:42:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:42:41 INFO - 'tests': ['tp5n']}}, 00:42:41 INFO - 'talos.zip': {'path': '', 00:42:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:42:41 INFO - Running post-action listener: _resource_record_post_action 00:42:41 INFO - [mozharness: 2016-11-22 08:42:41.999502Z] Finished populate-webroot step (success) 00:42:41 INFO - [mozharness: 2016-11-22 08:42:41.999633Z] Running create-virtualenv step. 00:42:41 INFO - Running pre-action listener: _resource_record_pre_action 00:42:41 INFO - Running main action method: create_virtualenv 00:42:42 INFO - Creating virtualenv /builds/slave/test/build/venv 00:42:42 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 00:42:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:42:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:42:42 INFO - Using real prefix '/tools/python27' 00:42:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:42:42 INFO - Installing distribute.............................................................................................................................................................................................done. 00:42:45 INFO - Installing pip.................done. 00:42:45 INFO - Return code: 0 00:42:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 00:42:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 00:42:46 INFO - Reading from file tmpfile_stdout 00:42:46 INFO - Output received: 00:42:46 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) 00:42:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:42:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c3b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb56a5e37c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a51d30>, '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 0x1048b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a49b20>, '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.Wmgagls9MT/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.gnj0n6JnkI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 00:42:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 00:42:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 00:42:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wmgagls9MT/Render', 00:42:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:42:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:42:46 INFO - 'HOME': '/Users/cltbld', 00:42:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:42:46 INFO - 'LOGNAME': 'cltbld', 00:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:42:46 INFO - 'MOZ_NO_REMOTE': '1', 00:42:46 INFO - 'NO_EM_RESTART': '1', 00:42:46 INFO - 'PAGER': '/bin/cat', 00:42:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:42:46 INFO - 'PWD': '/builds/slave/test', 00:42:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:42:46 INFO - 'SHELL': '/bin/bash', 00:42:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gnj0n6JnkI/Listeners', 00:42:46 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 00:42:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:42:46 INFO - 'USER': 'cltbld', 00:42:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:42:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:42:46 INFO - 'XPC_FLAGS': '0x0', 00:42:46 INFO - 'XPC_SERVICE_NAME': '0', 00:42:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:42:46 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 00:42:46 INFO - Cleaning up... 00:42:46 INFO - Return code: 0 00:42:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:42:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104c3b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb56a5e37c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a51d30>, '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 0x1048b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a49b20>, '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.Wmgagls9MT/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.gnj0n6JnkI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 00:42:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 00:42:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 00:42:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wmgagls9MT/Render', 00:42:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:42:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:42:46 INFO - 'HOME': '/Users/cltbld', 00:42:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:42:46 INFO - 'LOGNAME': 'cltbld', 00:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:42:46 INFO - 'MOZ_NO_REMOTE': '1', 00:42:46 INFO - 'NO_EM_RESTART': '1', 00:42:46 INFO - 'PAGER': '/bin/cat', 00:42:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:42:46 INFO - 'PWD': '/builds/slave/test', 00:42:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:42:46 INFO - 'SHELL': '/bin/bash', 00:42:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gnj0n6JnkI/Listeners', 00:42:46 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 00:42:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:42:46 INFO - 'USER': 'cltbld', 00:42:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:42:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:42:46 INFO - 'XPC_FLAGS': '0x0', 00:42:46 INFO - 'XPC_SERVICE_NAME': '0', 00:42:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:42:46 INFO - Downloading/unpacking psutil>=3.1.1 00:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:42:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:42:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:42:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:42:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:42:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:42:49 INFO - Installing collected packages: psutil 00:42:49 INFO - Running setup.py install for psutil 00:42:49 INFO - building 'psutil._psutil_osx' extension 00:42:49 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 00:42:50 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 00:42:50 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 00:42:50 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 00:42:50 INFO - building 'psutil._psutil_posix' extension 00:42:50 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 00:42:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:42:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:42:50 INFO - ^ 00:42:50 INFO - 1 warning generated. 00:42:50 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 00:42:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:42:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:42:51 INFO - Successfully installed psutil 00:42:51 INFO - Cleaning up... 00:42:51 INFO - Return code: 0 00:42:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:42:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:42:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c3b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb56a5e37c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a51d30>, '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 0x1048b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a49b20>, '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.Wmgagls9MT/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.gnj0n6JnkI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 00:42:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 00:42:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 00:42:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wmgagls9MT/Render', 00:42:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:42:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:42:51 INFO - 'HOME': '/Users/cltbld', 00:42:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:42:51 INFO - 'LOGNAME': 'cltbld', 00:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:42:51 INFO - 'MOZ_NO_REMOTE': '1', 00:42:51 INFO - 'NO_EM_RESTART': '1', 00:42:51 INFO - 'PAGER': '/bin/cat', 00:42:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:42:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:42:51 INFO - 'PWD': '/builds/slave/test', 00:42:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:42:51 INFO - 'SHELL': '/bin/bash', 00:42:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gnj0n6JnkI/Listeners', 00:42:51 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 00:42:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:42:51 INFO - 'USER': 'cltbld', 00:42:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:42:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:42:51 INFO - 'XPC_FLAGS': '0x0', 00:42:51 INFO - 'XPC_SERVICE_NAME': '0', 00:42:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:42:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:42:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:42:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:42:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:42:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:42:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:42:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:42:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:42:53 INFO - Installing collected packages: mozsystemmonitor 00:42:53 INFO - Running setup.py install for mozsystemmonitor 00:42:54 INFO - Successfully installed mozsystemmonitor 00:42:54 INFO - Cleaning up... 00:42:54 INFO - Return code: 0 00:42:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:42:54 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')]} 00:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:42:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:54 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')]} 00:42:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:42:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:54 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 0x104c3b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb56a5e37c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a51d30>, '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 0x1048b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a49b20>, '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.Wmgagls9MT/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.gnj0n6JnkI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 00:42:54 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 00:42:54 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 00:42:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wmgagls9MT/Render', 00:42:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:42:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:42:54 INFO - 'HOME': '/Users/cltbld', 00:42:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:42:54 INFO - 'LOGNAME': 'cltbld', 00:42:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:42:54 INFO - 'MOZ_NO_REMOTE': '1', 00:42:54 INFO - 'NO_EM_RESTART': '1', 00:42:54 INFO - 'PAGER': '/bin/cat', 00:42:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:42:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:42:54 INFO - 'PWD': '/builds/slave/test', 00:42:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:42:54 INFO - 'SHELL': '/bin/bash', 00:42:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gnj0n6JnkI/Listeners', 00:42:54 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 00:42:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:42:54 INFO - 'USER': 'cltbld', 00:42:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:42:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:42:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:42:54 INFO - 'XPC_FLAGS': '0x0', 00:42:54 INFO - 'XPC_SERVICE_NAME': '0', 00:42:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:42:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:42:54 INFO - Downloading/unpacking jsonschema==2.5.1 00:42:54 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 00:42:54 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 00:42:54 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 00:42:54 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 00:42:54 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 00:42:54 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 00:42:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:42:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:42:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:42:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:42:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:42:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:42:57 INFO - Downloading functools32-3.2.3-2.tar.gz 00:42:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:42:57 INFO - warning: no files found matching '*.txt' 00:42:57 INFO - no previously-included directories found matching 'build' 00:42:57 INFO - no previously-included directories found matching 'dist' 00:42:57 INFO - no previously-included directories found matching '.git*' 00:42:57 INFO - Installing collected packages: jsonschema, functools32 00:42:57 INFO - Running setup.py install for functools32 00:42:57 INFO - warning: no files found matching '*.txt' 00:42:57 INFO - no previously-included directories found matching 'build' 00:42:57 INFO - no previously-included directories found matching 'dist' 00:42:57 INFO - no previously-included directories found matching '.git*' 00:42:57 INFO - Successfully installed jsonschema functools32 00:42:57 INFO - Cleaning up... 00:42:57 INFO - Return code: 0 00:42:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:42:57 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')]} 00:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:42:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:57 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')]} 00:42:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:42:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:57 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 0x104c3b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb56a5e37c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a51d30>, '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 0x1048b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a49b20>, '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.Wmgagls9MT/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.gnj0n6JnkI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 00:42:57 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 00:42:57 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 00:42:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wmgagls9MT/Render', 00:42:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:42:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:42:57 INFO - 'HOME': '/Users/cltbld', 00:42:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:42:57 INFO - 'LOGNAME': 'cltbld', 00:42:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:42:57 INFO - 'MOZ_NO_REMOTE': '1', 00:42:57 INFO - 'NO_EM_RESTART': '1', 00:42:57 INFO - 'PAGER': '/bin/cat', 00:42:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:42:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:42:57 INFO - 'PWD': '/builds/slave/test', 00:42:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:42:57 INFO - 'SHELL': '/bin/bash', 00:42:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gnj0n6JnkI/Listeners', 00:42:57 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 00:42:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:42:57 INFO - 'USER': 'cltbld', 00:42:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:42:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:42:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:42:57 INFO - 'XPC_FLAGS': '0x0', 00:42:57 INFO - 'XPC_SERVICE_NAME': '0', 00:42:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:42:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:42:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:42:57 INFO - Cleaning up... 00:42:57 INFO - Return code: 0 00:42:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:42:57 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')]} 00:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:42:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:57 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')]} 00:42:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:42:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:57 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 0x104c3b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb56a5e37c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a51d30>, '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 0x1048b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a49b20>, '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.Wmgagls9MT/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.gnj0n6JnkI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 00:42:57 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 00:42:57 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 00:42:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wmgagls9MT/Render', 00:42:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:42:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:42:57 INFO - 'HOME': '/Users/cltbld', 00:42:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:42:57 INFO - 'LOGNAME': 'cltbld', 00:42:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:42:57 INFO - 'MOZ_NO_REMOTE': '1', 00:42:57 INFO - 'NO_EM_RESTART': '1', 00:42:57 INFO - 'PAGER': '/bin/cat', 00:42:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:42:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:42:57 INFO - 'PWD': '/builds/slave/test', 00:42:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:42:57 INFO - 'SHELL': '/bin/bash', 00:42:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gnj0n6JnkI/Listeners', 00:42:57 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 00:42:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:42:57 INFO - 'USER': 'cltbld', 00:42:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:42:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:42:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:42:57 INFO - 'XPC_FLAGS': '0x0', 00:42:57 INFO - 'XPC_SERVICE_NAME': '0', 00:42:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:42:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:42:58 INFO - Downloading/unpacking blobuploader==1.2.4 00:42:58 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 00:42:58 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 00:42:58 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 00:42:58 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 00:42:58 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 00:42:58 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 00:43:00 INFO - Downloading blobuploader-1.2.4.tar.gz 00:43:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:43:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:43:00 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 00:43:00 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 00:43:00 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 00:43:00 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 00:43:00 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 00:43:00 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 00:43:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:43:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:43:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:43:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:43:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:43:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:43:02 INFO - Downloading docopt-0.6.1.tar.gz 00:43:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:43:02 INFO - Installing collected packages: blobuploader, requests, docopt 00:43:02 INFO - Running setup.py install for blobuploader 00:43:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:43:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:43:02 INFO - Running setup.py install for requests 00:43:02 INFO - Running setup.py install for docopt 00:43:02 INFO - Successfully installed blobuploader requests docopt 00:43:02 INFO - Cleaning up... 00:43:03 INFO - Return code: 0 00:43:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:43: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')]} 00:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:43:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:43: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')]} 00:43:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:43:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:43:03 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 0x104c3b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb56a5e37c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a51d30>, '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 0x1048b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a49b20>, '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.Wmgagls9MT/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.gnj0n6JnkI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 00:43:03 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 00:43:03 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 00:43:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wmgagls9MT/Render', 00:43:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:43:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:43:03 INFO - 'HOME': '/Users/cltbld', 00:43:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:43:03 INFO - 'LOGNAME': 'cltbld', 00:43:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:43:03 INFO - 'MOZ_NO_REMOTE': '1', 00:43:03 INFO - 'NO_EM_RESTART': '1', 00:43:03 INFO - 'PAGER': '/bin/cat', 00:43:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:43:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:43:03 INFO - 'PWD': '/builds/slave/test', 00:43:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:43:03 INFO - 'SHELL': '/bin/bash', 00:43:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gnj0n6JnkI/Listeners', 00:43:03 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 00:43:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:43:03 INFO - 'USER': 'cltbld', 00:43:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:43:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:43:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:43:03 INFO - 'XPC_FLAGS': '0x0', 00:43:03 INFO - 'XPC_SERVICE_NAME': '0', 00:43:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:43:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:43:03 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-hFE9VO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:43:03 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ViJ2Dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:43:03 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-mPyRGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:43:03 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-1wWyJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:43:03 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-clMaW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:43:03 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-N50MMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:43:03 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-_tGqHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:43:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-X30sxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:43:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-7zC990-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:43:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-x6yS2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:43:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-lt3_BV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:43:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-5_P20w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:43:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-C47MzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:43:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-jvHvBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:43:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-2V0syd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:43:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-aoTrrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:43:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-xQVT6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:43:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:43:05 INFO - Running setup.py install for manifestparser 00:43:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:43:05 INFO - Running setup.py install for mozcrash 00:43:05 INFO - Running setup.py install for mozdebug 00:43:05 INFO - Running setup.py install for mozdevice 00:43:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:43:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:43:05 INFO - Running setup.py install for mozfile 00:43:05 INFO - Running setup.py install for mozhttpd 00:43:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:43:05 INFO - Running setup.py install for mozinfo 00:43:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:43:06 INFO - Running setup.py install for mozInstall 00:43:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:43:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:43:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:43:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:43:06 INFO - Running setup.py install for mozleak 00:43:06 INFO - Running setup.py install for mozlog 00:43:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:43:06 INFO - Running setup.py install for moznetwork 00:43:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:43:06 INFO - Running setup.py install for mozprocess 00:43:06 INFO - Running setup.py install for mozprofile 00:43:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:43:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:43:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:43:07 INFO - Running setup.py install for mozrunner 00:43:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:43:07 INFO - Running setup.py install for mozscreenshot 00:43:07 INFO - Running setup.py install for moztest 00:43:07 INFO - Running setup.py install for mozversion 00:43:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:43:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:43:07 INFO - Cleaning up... 00:43:07 INFO - Return code: 0 00:43:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:43:07 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')]} 00:43:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:43:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:43:07 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')]} 00:43:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:43:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:43:07 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 0x104c3b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb56a5e37c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a51d30>, '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 0x1048b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a49b20>, '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.Wmgagls9MT/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.gnj0n6JnkI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 00:43:07 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 00:43:07 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 00:43:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wmgagls9MT/Render', 00:43:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:43:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:43:07 INFO - 'HOME': '/Users/cltbld', 00:43:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:43:07 INFO - 'LOGNAME': 'cltbld', 00:43:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:43:07 INFO - 'MOZ_NO_REMOTE': '1', 00:43:07 INFO - 'NO_EM_RESTART': '1', 00:43:07 INFO - 'PAGER': '/bin/cat', 00:43:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:43:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:43:07 INFO - 'PWD': '/builds/slave/test', 00:43:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:43:07 INFO - 'SHELL': '/bin/bash', 00:43:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gnj0n6JnkI/Listeners', 00:43:07 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 00:43:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:43:07 INFO - 'USER': 'cltbld', 00:43:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:43:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:43:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:43:07 INFO - 'XPC_FLAGS': '0x0', 00:43:07 INFO - 'XPC_SERVICE_NAME': '0', 00:43:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:43:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:43:07 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ynh45R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:43:08 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)) 00:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:43:08 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-8WJOwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:43:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:43:08 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-UEJTRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:43:08 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)) 00:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:43:08 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-fFBGBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:43:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 00:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:43:08 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-bbA1OE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:43:08 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)) 00:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:43:08 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-89s5i8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:43:08 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)) 00:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:43:08 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ZinRYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:43:08 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)) 00:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:43:08 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-rJ26kI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:43:08 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)) 00:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:43:08 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Kov2_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:43:08 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)) 00:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:43:08 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-jLidWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:43:08 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)) 00:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:43:08 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-f8k9km-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:43:09 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)) 00:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:43:09 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-UcYCrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:43:09 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)) 00:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:43:09 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-4a88_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:43:09 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)) 00:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:43:09 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-lw0owc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:43:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 00:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:43:09 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-7jyMn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:43:09 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)) 00:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:43:09 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-C9Plad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:43:09 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)) 00:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:43:09 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-6X6T8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:43:09 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)) 00:43:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:43:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:43:09 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)) 00:43:09 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:43:09 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:43:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:43:09 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 00:43:09 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 00:43:09 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 00:43:09 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 00:43:09 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 00:43:09 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 00:43:12 INFO - Downloading blessings-1.6.tar.gz 00:43:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:43:12 INFO - Installing collected packages: blessings 00:43:12 INFO - Running setup.py install for blessings 00:43:12 INFO - Successfully installed blessings 00:43:12 INFO - Cleaning up... 00:43:12 INFO - Return code: 0 00:43:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:43:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:43:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:43:12 INFO - Reading from file tmpfile_stdout 00:43:12 INFO - Current package versions: 00:43:12 INFO - blessings == 1.6 00:43:12 INFO - blobuploader == 1.2.4 00:43:12 INFO - docopt == 0.6.1 00:43:12 INFO - functools32 == 3.2.3-2 00:43:12 INFO - jsonschema == 2.5.1 00:43:12 INFO - manifestparser == 1.1 00:43:12 INFO - mozInstall == 1.12 00:43:12 INFO - mozcrash == 1.0 00:43:12 INFO - mozdebug == 0.1 00:43:12 INFO - mozdevice == 0.49 00:43:12 INFO - mozfile == 1.2 00:43:12 INFO - mozhttpd == 0.7 00:43:12 INFO - mozinfo == 0.9 00:43:12 INFO - mozleak == 0.1 00:43:12 INFO - mozlog == 3.4 00:43:12 INFO - moznetwork == 0.27 00:43:12 INFO - mozprocess == 0.23 00:43:12 INFO - mozprofile == 0.28 00:43:12 INFO - mozrunner == 6.13 00:43:12 INFO - mozscreenshot == 0.1 00:43:12 INFO - mozsystemmonitor == 0.3 00:43:12 INFO - moztest == 0.7 00:43:12 INFO - mozversion == 1.4 00:43:12 INFO - psutil == 3.1.1 00:43:12 INFO - requests == 1.2.3 00:43:12 INFO - wsgiref == 0.1.2 00:43:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:43:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:43:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:43:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:43:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:43:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:43:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:43:12 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 0x104c3b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb56a5e37c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a51d30>, '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 0x1048b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a49b20>, '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.Wmgagls9MT/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.gnj0n6JnkI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 00:43:12 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 00:43:12 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 00:43:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wmgagls9MT/Render', 00:43:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:43:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:43:12 INFO - 'HOME': '/Users/cltbld', 00:43:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:43:12 INFO - 'LOGNAME': 'cltbld', 00:43:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:43:12 INFO - 'MOZ_NO_REMOTE': '1', 00:43:12 INFO - 'NO_EM_RESTART': '1', 00:43:12 INFO - 'PAGER': '/bin/cat', 00:43:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:43:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:43:12 INFO - 'PWD': '/builds/slave/test', 00:43:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:43:12 INFO - 'SHELL': '/bin/bash', 00:43:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gnj0n6JnkI/Listeners', 00:43:12 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 00:43:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:43:12 INFO - 'USER': 'cltbld', 00:43:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:43:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:43:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:43:12 INFO - 'XPC_FLAGS': '0x0', 00:43:12 INFO - 'XPC_SERVICE_NAME': '0', 00:43:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:43:13 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)) 00:43:13 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)) 00:43:13 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)) 00:43:13 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)) 00:43:13 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)) 00:43:13 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)) 00:43:13 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)) 00:43:13 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)) 00:43:13 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)) 00:43:13 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)) 00:43:13 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)) 00:43:13 INFO - Cleaning up... 00:43:13 INFO - Return code: 0 00:43:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:43:13 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')]} 00:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:43:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:43:13 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')]} 00:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:43:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:43:13 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 0x104c3b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb56a5e37c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a51d30>, '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 0x1048b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a49b20>, '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.Wmgagls9MT/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.gnj0n6JnkI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 00:43:13 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 00:43:13 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 00:43:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wmgagls9MT/Render', 00:43:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:43:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:43:13 INFO - 'HOME': '/Users/cltbld', 00:43:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:43:13 INFO - 'LOGNAME': 'cltbld', 00:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:43:13 INFO - 'MOZ_NO_REMOTE': '1', 00:43:13 INFO - 'NO_EM_RESTART': '1', 00:43:13 INFO - 'PAGER': '/bin/cat', 00:43:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:43:13 INFO - 'PWD': '/builds/slave/test', 00:43:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:43:13 INFO - 'SHELL': '/bin/bash', 00:43:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gnj0n6JnkI/Listeners', 00:43:13 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 00:43:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:43:13 INFO - 'USER': 'cltbld', 00:43:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:43:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:43:13 INFO - 'XPC_FLAGS': '0x0', 00:43:13 INFO - 'XPC_SERVICE_NAME': '0', 00:43:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:43:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:43:13 INFO - Cleaning up... 00:43:13 INFO - Return code: 0 00:43:13 INFO - Running post-action listener: _resource_record_post_action 00:43:13 INFO - Running post-action listener: _start_resource_monitoring 00:43:13 INFO - Starting resource monitoring. 00:43:13 INFO - [mozharness: 2016-11-22 08:43:13.358014Z] Finished create-virtualenv step (success) 00:43:13 INFO - [mozharness: 2016-11-22 08:43:13.359014Z] Running install step. 00:43:13 INFO - Running pre-action listener: _resource_record_pre_action 00:43:13 INFO - Running main action method: install 00:43:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:43:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:43:13 INFO - Reading from file tmpfile_stdout 00:43:13 INFO - Detecting whether we're running mozinstall >=1.0... 00:43:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:43:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:43:13 INFO - Reading from file tmpfile_stdout 00:43:13 INFO - Output received: 00:43:13 INFO - Usage: mozinstall [options] installer 00:43:13 INFO - Options: 00:43:13 INFO - -h, --help show this help message and exit 00:43:13 INFO - -d DEST, --destination=DEST 00:43:13 INFO - Directory to install application into. [default: 00:43:13 INFO - "/builds/slave/test"] 00:43:13 INFO - --app=APP Application being installed. [default: firefox] 00:43:13 INFO - mkdir: /builds/slave/test/build/application 00:43:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 00:43:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 00:43:46 INFO - Reading from file tmpfile_stdout 00:43:46 INFO - Output received: 00:43:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 00:43:46 INFO - Running post-action listener: _resource_record_post_action 00:43:46 INFO - [mozharness: 2016-11-22 08:43:46.403659Z] Finished install step (success) 00:43:46 INFO - [mozharness: 2016-11-22 08:43:46.403797Z] Running run-tests step. 00:43:46 INFO - Running pre-action listener: _resource_record_pre_action 00:43:46 INFO - Running main action method: run_tests 00:43:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:43:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:43:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:43:46 INFO - 2016-11-22 00:43:46.418 screenresolution[1622:7333] starting screenresolution argv=screenresolution get 00:43:46 INFO - 2016-11-22 00:43:46.420 screenresolution[1622:7333] Display 0: 1600x1200x32@60 00:43:46 INFO - 2016-11-22 00:43:46.426 screenresolution[1623:7335] starting screenresolution argv=screenresolution list 00:43:46 INFO - Available Modes on Display 0 00:43:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:43:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:43:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:43:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:43:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:43:46 INFO - Intel Iris: 00:43:46 INFO - Chipset Model: Intel Iris 00:43:46 INFO - Type: GPU 00:43:46 INFO - Bus: Built-In 00:43:46 INFO - VRAM (Dynamic, Max): 1536 MB 00:43:46 INFO - Vendor: Intel (0x8086) 00:43:46 INFO - Device ID: 0x0a2e 00:43:46 INFO - Revision ID: 0x0009 00:43:46 INFO - Displays: 00:43:46 INFO - Display: 00:43:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:43:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:43:46 INFO - Display Serial Number: 1600x1200 60 00:43:46 INFO - Main Display: Yes 00:43:46 INFO - Mirror: Off 00:43:46 INFO - Online: Yes 00:43:46 INFO - Rotation: Supported 00:43:46 INFO - Return code: 0 00:43:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:43:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:43:46 INFO - Python 2.7.3 00:43:46 INFO - Return code: 0 00:43:46 INFO - grabbing minidump binary from tooltool 00:43:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:43:46 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 0x104a51d30>, '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 0x1048b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a49b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:43:46 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 00:43:46 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 00:43:46 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 00:43:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:43:46 INFO - Return code: 0 00:43:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:43:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:43:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:43:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:43:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:43:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--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 00:43:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0119 --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 00:43:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wmgagls9MT/Render', 00:43:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:43:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:43:46 INFO - 'HOME': '/Users/cltbld', 00:43:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:43:46 INFO - 'LOGNAME': 'cltbld', 00:43:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:43:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:43:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:43:46 INFO - 'MOZ_NO_REMOTE': '1', 00:43:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:43:46 INFO - 'NO_EM_RESTART': '1', 00:43:46 INFO - 'PAGER': '/bin/cat', 00:43:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:43:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:43:46 INFO - 'PWD': '/builds/slave/test', 00:43:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:43:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:43:46 INFO - 'SHELL': '/bin/bash', 00:43:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gnj0n6JnkI/Listeners', 00:43:46 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 00:43:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:43:46 INFO - 'USER': 'cltbld', 00:43:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:43:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:43:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:43:46 INFO - 'XPC_FLAGS': '0x0', 00:43:46 INFO - 'XPC_SERVICE_NAME': '0', 00:43:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:43:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--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 00:43:47 INFO - mozversion application_buildid: 20161121214150 00:43:47 INFO - mozversion application_changeset: 805d42464705b0c6d7db1101c1550dc4855b4329 00:43:47 INFO - mozversion application_display_name: Nightly 00:43:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:43:47 INFO - mozversion application_name: Firefox 00:43:47 INFO - mozversion application_remotingname: firefox 00:43:47 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 00:43:47 INFO - mozversion application_vendor: Mozilla 00:43:47 INFO - mozversion application_version: 53.0a1 00:43:47 INFO - mozversion platform_buildid: 20161121214150 00:43:47 INFO - mozversion platform_changeset: 805d42464705b0c6d7db1101c1550dc4855b4329 00:43:47 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 00:43:47 INFO - mozversion platform_version: 53.0a1 00:43:47 INFO - using testdate: 1479804227 00:43:47 INFO - actual date: 1479804227 00:43:47 INFO - starting webserver on 'localhost:49240' 00:43:47 INFO - SUITE-START | Running 2 tests 00:43:47 INFO - TEST-START | dromaeo_css 00:43:47 INFO - operating with platform_type : mac_ 00:43:47 INFO - Initialising browser for dromaeo_css test... 00:43:47 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpnrdLeU/profile http://localhost:49240/getInfo.html) 00:43:49 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 00:43:49 INFO - PROCESS | 1633 | colorDepth:24 00:43:49 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 00:43:49 INFO - PROCESS | 1633 | __metrics 00:43:49 INFO - PROCESS | 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:43:49 INFO - PROCESS | 1633 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:43:50 INFO - TEST-INFO | 1633: exit 0 00:43:50 INFO - Browser initialized. 00:43:50 INFO - Running cycle 1/1 for dromaeo_css test... 00:43:50 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpnrdLeU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:44:03 INFO - PROCESS | 1635 | RSS: Main: 236331008 00:44:03 INFO - PROCESS | 1635 | 00:44:54 INFO - PROCESS | 1635 | 1479804294648 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] 00:47:08 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 00:47:09 INFO - PROCESS | 1635 | RSS: Main: 396357632 00:47:09 INFO - PROCESS | 1635 | 00:50:12 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 00:50:13 INFO - PROCESS | 1635 | RSS: Main: 362008576 00:50:13 INFO - PROCESS | 1635 | 00:53:15 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 00:53:16 INFO - PROCESS | 1635 | RSS: Main: 344682496 00:53:16 INFO - PROCESS | 1635 | 00:56:18 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 00:56:19 INFO - PROCESS | 1635 | RSS: Main: 341676032 00:56:19 INFO - PROCESS | 1635 | 00:59:22 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 00:59:23 INFO - PROCESS | 1635 | RSS: Main: 337534976 00:59:23 INFO - PROCESS | 1635 | 01:02:26 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 01:02:27 INFO - PROCESS | 1635 | RSS: Main: 378363904 01:02:27 INFO - PROCESS | 1635 | 01:02:27 INFO - PROCESS | 1635 | __start_tp_report 01:02:27 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 01:02:27 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 01:02:27 INFO - PROCESS | 1635 | |i|pagename|runs| 01:02:27 INFO - PROCESS | 1635 | |0;dojo.html;2801.831890086596;3042.391521695661;3172.936541269173;3290.30903510263;3321.103302108431;5500.752466139023;5506.0089183946975;5507.118860982244;5528.253685752422;5592.944070559293;5534.08687566551;5575.553955683546;5583.162525621159;5617.8033768818095;5631.1271752878265;4989.176785830334;5073.771680274378;5129.692218466886;5140.768665410065;5236.528712415882;5189.792408303663;5232.764525596357;5237;5243.082460569404;5292.391374991887;5063.41770696371;5106.463821298785;5187.948120518784;5195.19474481456;5196.246544251081;5146.639735218536;5153.484651534854;5159.871003224912;5161.148410512261;5163.173892177234;5081.263216833576;5107.055194788976;5110.156824124052;5126.769295381717;5129.384473863123;5539.529140023103;5584.692841893694;5587.664740115606;5594.104943209108;5598.748056337475;6002.189704389895;6007.8197654070445;6009.369016253274;6011.939880601181;6060.757569697251;6008.729607167645;6044.304904935901;6159.384061593805;6185.56701030928;6188.721507532172;4747.2879068140055;5613.270274864323;5618.101103823368;5642.463965923313;5689.743961521742;5035.723035233024;6285.088662143963;6308.400701933309;6312.652804095727;6327.651979141191;6052.576319657582;6068.605540639845;6070.514358851281;6080.361562036011;6080.361562036011;6035.849103772353;6051.364606716319;6052.515798736091;6095.329513753483;6174.197354343906;6041.697915104227;6117.490066423026;6118.9846826261555;6129.981545214243;6144.187464697925;6130.459285710791;6143.877122457526;6148.538859585459;6149.754009839646;6161.291451483136;4707.505711900201;4815.133276010351;4821.252705830602;4822.107910036654;4827.2035114206465;3612.6387361263664;4026.7181297308907;4351.97824010878;4378.64970802335;4382.298832186835;5560.193771903051;5616.719164041781;5646.040173170571;5653;5663.631863928831;6019.518438524908;6063.272407311151;6153.230846144232;6197.566170368121;6207.689615519206;5518.724063796794;5535.557155427556;5599.580031497654;5601.271834661468;5608.439156084359;5277.23480095384;5277.762500687479;5291.285676433632;5298.231756395301;5309;6219.968900155561;6268.62388256697;6280.8429789253805;6286.119943208046;6288.0882272069375;5080.974595127001;6107.572469927062;6110.663913484801;6115.4190351916495;6129.785457508966;6214.751409943552;6239.844003899939;6248.875022499434;6250.843728906813;6261.68691565429;5914.674692891932;5922.022866227197;5924.11138329254;5928.170056192051;5930.406959304035;5383.327084114486;6137.171481849893;6193.411625895533;6255.812325630056;6266.248050234001;6057.4851137653795;6058;6062.636241825505;6064.878702426015;6067.939320606737;5790.536757059341;5860.824175274749;5870.17817505541;5937;6011.428914253139;5842.620229685057;5847.327557330961;5851.561132915133;5863.472287494146;5915.58590898633;5881.38245484218;5993.580449368503;6002.939970600238;6026.337102918763;6050.818475445568;5653.1802888580105;5726.4273572642405;5731.8853622928145;5740.253767010262;5827.679477628772 01:02:27 INFO - PROCESS | 1635 | |1;ext.html;449.6268097479091;468.7960737079373;501.13554119144476;513.6789506558401;516.3622925686778;30188.396232075374;31953.721851125956;39984.400233996515;40995.38506922399;41180.76457706266;41799.58200417995;42214;42222;42278.788606057;42297;9138.999999999984;9327.207187389045;9330.346875718702;9331.813363732721;9335.206507446876;9321.580528876217;9413.388129771578;9417.529123543829;9420.952895235514;9421.387609805375;9384.671536496224;9442.527873606326;9445.480498572575;9449.338546301728;9461.95269023654;9328.253739700842;9390.248780097643;9402.200812930909;9406.200472959737;9412.764680882998;9250.259979201648;9337.859932101024;9357.111074447917;9361.531923403803;9361.625534978593;39459.21081578381;39745.80127099375;39764.403533947014;39820.60179398198;39872.80381588528;3305.8512366943305;3370.5281260623533;3405.5743032120986;3676.4117741161554;3939.0940083780606;29722;30534.84732576345;30637.234069148228;30644.233894152603;30794.84602576995;3197.9680203197904;3216.694414030687;3245.432049391347;3257.9022629321157;3350.798952062884;363014;479406;485641;486414;493906.5304673454;61432.77134457286;61924.38075619186;61945.690271549254;62163.37836621576;62207.6889615549;67530.66234668795;69752.30247697458;69865.30134698689;69904.65047674831;73430.63284683542;14919.40322387114;15059.924700376429;15368.154751488551;15372.000819946668;15382.692346153239;12262.938685306517;12386.690332741573;12411.007119430604;12412.317322547166;12422.130450868533;19610.215591376185;20141;20188.39434816958;20199.596008079756;20204.898975505323;8126;8193.385496087818;8232.39468303687;8276.379271554657;8282.50304981703;847.5211505499373;848.5036253791478;924.7734305095295;975.9024097590184;1094.0919037199105;24335.634965475532;27909.720902790712;28220.01229956942;29406.264843379086;30217.69782302193;994.8470497125644;1049.275999560301;1065.6696424108552;1065.6962765611918;1107.828286615576;14759.262036898326;15320.92753507266;15355.692886142215;15608.3756649735;15703.979241349276;668848;671622;671650;672357;673240;57781.711091444275;58320;58417.450342217424;58426.7078664604;58646;1305.2755720574983;1349.5344106283317;1364.3996641477718;1366.7403193393222;1367.8085068090475;28152.43695126127;29014.56478152787;29108.27229319284;29797.85101074481;29851.775939210118;51120;51348;51468;51556.74221628868;51624.48375516197;489822;493760;494002;494803;494839;41278.174436510504;41400.1719965605;41994.58005420029;42008;42265;44464.55535444604;45619;46478;46527.53472465232;47483.52516474791;32429.02712918616;35921.46117808287;37909.05227369227;38213.61786382212;39293.41059884161;38999.6100038996;41278.587214128675;42111.7894410526;42395.364069538366;42565.787171063945;34571.82714086414;38662.80668596639;38981.22037559289;39820.2035959285;40854.9786255346;30787.692123078483;39708.801455992536;40376;42111.7894410526;42248.78875605603;29675.85162074176;30105.09684709462;30984;31103.68896311008;31665.25841078763 01:02:27 INFO - PROCESS | 1635 | |2;jquery.html;346.46990105139145;369.123332086295;369.2209438085639;382.4149051950516;398.94015897615355;12815.615531534046;14483.275836208188;15672.764908526367;19781.604367912634;20339;19097.809021909776;19187;19548.511287217825;19727.5068123297;19745;5029.597632189416;5251.5798736101215;5262.710550919698;5266.762995665185;5271.15661494161;4691;5155.871103222431;5176.663516871391;5199.376074871002;5214.843554693346;5207.895842083156;5214.165733482643;5219.765110570014;5223.895522089556;5226.660267082647;5130.999999999981;5218.660787048849;5639.323281206241;5681.829545113653;5695.202671625934;4928.630352723524;5052.520010598987;5058.291839142523;5059.848204553869;5060.721660308682;12829.358532073358;16366.772492063143;23343.88328058349;24745.876270618533;25014.249572512854;2134.1036764558767;2147.656374980011;2193.7257842769654;2225.109956017606;2258.6047441697638;9734.853977190347;9804;9947.850782238273;9987.500624968796;10137.493125343777;1792.5160206744235;1805.124514610413;1815.936442224529;1913.6268427656612;1937.9515512112168;123377;126943;129068.35465822705;129434.05848912368;129889;10989.340639561651;11371.943140284246;11386.032187264009;11403.600873969372;11487.71280717972;11635;13514.391852366667;14343.713125737428;14350.354234059492;14356.353964071643;6787.796366109024;7057.788266352018;8354.749357519284;8357.958210209032;8499.872501912352;8016.198380161937;8111.513309201467;8182.509049457051;8182.590870456454;8368.539730314891;4409.030013397048;4418.138462999685;4427.955720442755;4436.911261774746;4513.977430112894;1804.900730459811;1824.9087545622665;1856.7281412232664;1892.8391086757504;1896.3552392186723;722.6964674836581;832.3216578488513;908.504864848659;923.5520772425368;947.8104379124203;19843;21247;21352.466188345104;21442.571148576943;21577.590480912248;882.7440042387764;912.4570880326195;935.1577392536692;972.6917296236551;1000.6447711062614;10586.629467968738;11026.117910567136;11135.993281813864;11173.441327933571;11196.992270695677;549091;557057.2147139319;561366;562965;586893.0655346696;129971;132487;134968;135807.96288055688;137088.31455842656;939.7133874168308;944.9338546301831;970.5777986575862;1013.366645846346;1014.1278500489573;18885.339013134475;19626.705599415734;19649.508762280486;19649.70525442091;19659.60680786405;48734.512654873;53432;53736.462635373144;54735.45264547304;55023.72488137534;77470.61264693641;77974;79691.4061718774;82065.58967205125;86600.56699716461;7004.194517630538;7224.819379515554;7346.191918888811;7500.062492188476;7875.448718589873;11497;12893.097483176087;12929.482820687068;13090.14914030585;13327.067105302536;10892.509837057192;10948.890511094787;11368.204225704121;11604.825927610924;11638.185327027028;8951.776205594912;10873.891261087289;11161.441927903736;11513.654590362303;11576.594819181626;11067.391293478937;11237.550497980315;11808.114391420506;11835;11837.810005149075;7319.670614822239;7442.032535770315;7504.774856754306;7536.962315188389;7570.810729731801;9119.361644684808;10859.565617375216;10944.562217511211;11198.04816590599;11552.82670759956 01:02:27 INFO - PROCESS | 1635 | |3;mootools.html;1316.0655934286656;1494.9551513454594;1513.3643869574778;1682.5709444091756;1847.4088291746639;7364.1163060432755;7781.455298129126;7842.843143137141;8191.426600137993;8209.261166495015;7920.564368959705;8215.630296636651;8222.383321250914;8222.753317400473;8226.670933162666;2252.1892118837245;2310.5905397707384;2311.225739377302;2313.53729254149;2315.8957846896847;2282.589133955887;2322;2331.5803155432013;2339.333290012347;2341.379534423379;1967.6753335699593;2272.9545409091807;2273.8408311418207;2299.908003679859;2302.8618282902994;2224.077008041661;2255.1655887321795;2286.302677683306;2287.782660647236;2288.347820871044;2289.771022897697;2307.111761971636;2311.780380863815;2315.4211447138214;2316.5598536278053;8080.191980801932;8243.876341854877;8255.25702686761;8277.83444331116;8280.213379728915;4903.975480122576;5105.259737338065;5109.233614957749;5131.050755610187;5138.100832354323;3093.2266933266683;3288.490284005983;3454.844531996041;3509.4560343146854;3569.785812851237;1020.6274709730969;1057.9365238085663;1071;1088.1566785741113;1097.7639807441437;10626.681199564026;10676.839847402296;10814.188935829845;10827.621033263798;10829.133669306437;4422.270325396338;4503.2569626012;4520.050789334211;4588.128255631419;4594.632429405639;9351.48566828831;9365.11031452011;9374.062593740708;9386.249100071978;9451;5135.435102138762;5142.254373115878;5147.819826306061;5155;5168.767405466763;3964.385520244367;4201.705880588391;4341.19687857741;4363.650907927358;4616.215243408628;3724.888253352404;3764.5294338207723;3835.9041023974623;3859.3439115350457;3864.478295430081;1217.6103646833105;1240.5968060380412;1242.1118899986552;1246.4764798784531;1251.9436625351882;867.509856930832;871.9694810681723;882.2589025218847;909.8666454992756;914.5061666700041;5343.759530821122;5528.154744843014;5628.746706398222;5674.779922316639;5750.712464376848;438.6600384701818;447.35804121086346;448.7800977520991;451.27570249749334;924.4458521183642;3340.5991281045945;3673.3204357194004;3767.1335592813807;3815.5802861685193;3875.670568001695;9143.13140251675;9646.083622055894;9924.156446701967;9938.204943604494;10097.495125243855;9956.253281003954;10054.245931555162;10060.69817905464;10061.899381006097;10096.242781791396;6516.641584712792;7507.812304692427;7631.3894888408795;7787.688492460352;7801.180876008051;5339.626226164131;5393.865153371197;5444.482774136451;5502.862428439321;5677.347105082803;9229.215516680888;9250.583723732314;9263;9314.95342523283;9334.626614935598;8527.403081784216;8871.955640221757;9087.273018158663;9557;9586.520673966133;6279.529035322278;6455;6604.8348791280605;6678.632675202716;6739.898901516579;7687.3570502094035;7809.33620642229;7874.72438464651;7893.092294386219;7904.328132108835;5567;6028.728707208274;6178.62928224308;7000.649967501707;7098.609576473344;6814.250432452327;6888.138982627172;6919.688614012281;6941.965290173517;6972.4073453757;6456.612603243821;6583.0454584086;6587.440067594308;6590.670466476561;6600.372964568358;4666.929996049994;4800.687955282895;4815.879603009953;4851.393575803025;4918.2376731606655;4684.953150468452;4789.066132104345;4821.63837712166;4855.271709243641;4894.755262236803 01:02:27 INFO - PROCESS | 1635 | |4;prototype.html;293.60216906092245;298.9297515083955;305.70652173913044;311.08540889784035;313.8807253243767;4832.71003739775;4985.576226020793;5008.32054850655;5040.697558146514;5070.264811602316;4244.957550424495;4850.442199147099;4899.31409602655;4908.50914908509;4944.5055494450535;3839.9824046627664;3859.421086836983;3877.612238776128;3885.7668539887554;3933.0727943307716;2820.9012684556046;3771.849126034955;3847.2882516734353;3901.31726947783;3975.7216994810374;3850.4994350734505;3859.1895701902636;3859.8456061757497;3871.187050719352;3905.2189562087556;3463.9826800866085;3834.657869745595;3841.385378339466;3855.209682015194;3858.054776579756;2806.8456234907276;3148;3373.26276967362;3373.7301015918915;3404.6254911959663;4010.3984402339593;4215.220184265921;4368.929612244989;4850.927236091461;4896.534829191221;4064.9837540614844;4353.25994580922;4416.823327066913;4428.446444194476;4450.46594408673;4189.790510474494;4898.975505122452;5144.588432925394;5198.038362902837;5205.349331333583;3733.234686889208;3735.383661695817;3737.4767532545466;3738.7569807962395;3739.719521035906;4362;4604.907901841944;5157.5616072633875;5366.624336296461;5465.988792073439;3070.4012717519868;3199.9520007199912;3694.9445758313645;3750.9182403765626;3757.6242375762204;3354.6645335466746;3793.677537409296;3809.1810260794064;3844.442555829389;3845.731018698981;4081.591840815954;4347.130733784503;4399.472063352418;4409.927334153007;4413.7793110345;4823.831165909176;4982.277569752366;5271.446498117719;5289.418164001957;5292.941411717672;970.9562220612869;972.4262685015875;1081.0000749307017;1108.7283615514089;1181.4565299962114;755.7317152410925;842.2924743215729;847.4576271186446;864.7708357285325;865.7056600755773;3876.651101400831;4060.4315395844606;4555.726656400627;4556.954430455653;4595.931061034087;4373.059792144704;4421.579949904753;4584.381108550369;4775.95224047762;4918.360613120343;4255.659547236213;4290.841472802326;4356.215881141425;4574.771261436981;4597.563231493003;2304.873231972224;2497.7002759668594;2593.338698631837;2630.631711560383;2666.600009998477;4164.876342742943;4370.454727083861;4372.813593203398;5139.939447836167;5205.9393672935175;4322.22200003996;4703.459102203222;4846.224604063333;4883.3651625288485;5178.508041736029;4668.688998945476;5192.324997750268;5194.714290714047;5233.319668443154;5237.019864902625;4310.913781724411;4415.22733521626;4775.355327030807;4970.8757281068265;4992.72540010303;4577.198990176772;4824.962633033914;4957.950420495749;4976.477469865612;5013.997200559829;4384.605385515319;4650.9767451162525;4780.426348838162;4996;5174.586033117266;3169.286910445224;3450.46517789743;3511;3524.8766293179615;3539.823008849599;4295.334223195409;4721.315409265706;4780.1873681473535;5000.774965126505;5016.473270306711;4576.8626941191815;4802.975985120052;4837.37114175155;4859.47307400157;4891.779869905934;3947.8618248361167;4344.934825977676;4490.685652004328;4834.371531700997;4874.926876096933;4433.889152771207;4633.8146474142;4738.170820106537;4742.217534106834;4762.714067201905;4520.412346394947;4656.650751193606;4789.449213340371;4856.63575231852;4877.317175595348;3605.6033836277466;4392.450943632046;4426.867193984185;4560.67405623128;4566.178087944069 01:02:27 INFO - PROCESS | 1635 | |5;yui.html;713;730.3718801830424;759.9164091949885;783.2363445640498;796.5539297993123;14473.34869930853;15127.016743911638;15150.318235679393;15162.317695703692;15189.936704430695;12409.813852792215;12481.750364992695;12483.126181167321;12490.12569120162;12500.624981250554;674.6626686656672;696.1402667705405;822.847773161967;831.3764676492631;859.5401460218784;840.7645859159445;844.4468872888268;846.2595229174473;846.3398549131687;852.9744107676779;898.27239935652;900.9909900900973;903.4850135422812;904.6245807989676;906.3700727994046;893.5264309915723;894.932880034;896.12627688004;896.8699538566872;898.6360523987795;514.2286570144782;538.8464287677992;542.2435702195415;544.28154835617;545.5662748115258;12979.41592628334;13537.1200871943;13544.322783860865;13830.65423364424;13851.376688049062;11236.763955964838;13147.408366623526;13497.527586534523;13529;13628.386722597506;10158.847617285746;10439.269251152422;11049.392283424408;11137.164712646583;11563.479643416067;3643.708503319728;3665.798381089039;3665.963340366589;3696.464012718141;3796.1079146400575;84379.5781021091;85226;85705.14294857034;85817.28365432781;86575;10271.486425678686;10401.53193106312;10549.47252637365;10754.946225268981;10782.622608208674;19787.911664858282;19884.565071946967;20385.49036274105;20420.081096350405;20434.080466379048;7580.317771400601;7661.846763064707;7686.3082322591235;7845.215478452109;7890.605469726491;4230.428892099589;4243.872683819491;4259.531451540328;4291.957080429156;4377.299632058855;3362.5628668272966;3482.7213822894614;3485;3492.1793378556017;3492.790432574003;638.090720722968;652.5301782716436;653.7809833705762;660.3363619562405;660.6168422315046;638.5370606310481;643.7457204404234;658.720043981306;668.66144068845;683.255251775567;8581.485110893367;8799;8914.242289405473;8971.058038905821;9099.35839779341;567.1690972724896;575.5251917168353;600.9849753756104;613.2953674296332;614.3395849461757;3521.4717792331357;4081.8775436736946;4333.414988976511;4364.388985542027;4591.334256532784;77199.61400192963;81432;107880.8423831519;110236.448817757;113935;64009.679951600876;77770.61114694504;77921.61039194767;108206;111555;78303.60848195723;86022.13977860266;86474;86687.69968450605;88488.55755721309;9294.349395542518;9493.288003399635;9604.423734575948;9677.661281855102;9697.563609637724;53823.73088134534;55502;55604.173007169615;57811.710941446705;59058.40941590701;53006.73496632492;63257.367426325145;67837.32162678508;74201;77686.2231377679;9457.668981585886;12601.747965040464;12723.427445764777;12872.613821585368;13620.114692544952;13825;13843.377048033064;14173.645658858612;14209.431622734975;14796.408143674564;12725.172863763826;12865.938056565543;12948.870511294766;13001.609951701465;13015.958723302303;12972.28652424088;13022.609321720363;13389.19664820114;13416.32918354098;13892.791608125684;12233.877661223274;12292.631221063382;12473.812892806796;13036;13572.86427135716;10078.605570958589;10114.190864730952;10467.214958877963;10591.5233814477;10812.513436895515;9944.651937182163;10666.520006599567;10727.624533141303;10731.678049658523;10983.011528962432 01:02:27 INFO - PROCESS | 1635 | __end_tp_report 01:02:27 INFO - PROCESS | 1635 | __start_cc_report 01:02:27 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,1700 01:02:27 INFO - PROCESS | 1635 | __end_cc_report 01:02:27 INFO - PROCESS | 1635 | __startTimestamp1479805347000__endTimestamp 01:02:27 INFO - PROCESS | 1635 | 01:02:27 INFO - PROCESS | 1635 | ------- Summary: start ------- 01:02:27 INFO - PROCESS | 1635 | Number of tests: 6 01:02:27 INFO - PROCESS | 1635 | 01:02:27 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5610.97 Median:5790.54 stddev:666.66 (11.5%) stddev-sans-first:631.23 01:02:27 INFO - PROCESS | 1635 | Values: 2801.8 3042.4 3172.9 3290.3 3321.1 5500.8 5506.0 5507.1 5528.3 5592.9 5534.1 5575.6 5583.2 5617.8 5631.1 4989.2 5073.8 5129.7 5140.8 5236.5 5189.8 5232.8 5237.0 5243.1 5292.4 5063.4 5106.5 5187.9 5195.2 5196.2 5146.6 5153.5 5159.9 5161.1 5163.2 5081.3 5107.1 5110.2 5126.8 5129.4 5539.5 5584.7 5587.7 5594.1 5598.7 6002.2 6007.8 6009.4 6011.9 6060.8 6008.7 6044.3 6159.4 6185.6 6188.7 4747.3 5613.3 5618.1 5642.5 5689.7 5035.7 6285.1 6308.4 6312.7 6327.7 6052.6 6068.6 6070.5 6080.4 6080.4 6035.8 6051.4 6052.5 6095.3 6174.2 6041.7 6117.5 6119.0 6130.0 6144.2 6130.5 6143.9 6148.5 6149.8 6161.3 4707.5 4815.1 4821.3 4822.1 4827.2 3612.6 4026.7 4352.0 4378.6 4382.3 5560.2 5616.7 5646.0 5653.0 5663.6 6019.5 6063.3 6153.2 6197.6 6207.7 5518.7 5535.6 5599.6 5601.3 5608.4 5277.2 5277.8 5291.3 5298.2 5309.0 6220.0 6268.6 6280.8 6286.1 6288.1 5081.0 6107.6 6110.7 6115.4 6129.8 6214.8 6239.8 6248.9 6250.8 6261.7 5914.7 5922.0 5924.1 5928.2 5930.4 5383.3 6137.2 6193.4 6255.8 6266.2 6057.5 6058.0 6062.6 6064.9 6067.9 5790.5 5860.8 5870.2 5937.0 6011.4 5842.6 5847.3 5851.6 5863.5 5915.6 5881.4 5993.6 6002.9 6026.3 6050.8 5653.2 5726.4 5731.9 5740.3 5827.7 01:02:27 INFO - PROCESS | 1635 | 01:02:27 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:68834.47 Median:42059.89 stddev:147045.78 (349.6%) stddev-sans-first:147368.64 01:02:27 INFO - PROCESS | 1635 | Values: 449.6 468.8 501.1 513.7 516.4 30188.4 31953.7 39984.4 40995.4 41180.8 41799.6 42214.0 42222.0 42278.8 42297.0 9139.0 9327.2 9330.3 9331.8 9335.2 9321.6 9413.4 9417.5 9421.0 9421.4 9384.7 9442.5 9445.5 9449.3 9462.0 9328.3 9390.2 9402.2 9406.2 9412.8 9250.3 9337.9 9357.1 9361.5 9361.6 39459.2 39745.8 39764.4 39820.6 39872.8 3305.9 3370.5 3405.6 3676.4 3939.1 29722.0 30534.8 30637.2 30644.2 30794.8 3198.0 3216.7 3245.4 3257.9 3350.8 363014.0 479406.0 485641.0 486414.0 493906.5 61432.8 61924.4 61945.7 62163.4 62207.7 67530.7 69752.3 69865.3 69904.7 73430.6 14919.4 15059.9 15368.2 15372.0 15382.7 12262.9 12386.7 12411.0 12412.3 12422.1 19610.2 20141.0 20188.4 20199.6 20204.9 8126.0 8193.4 8232.4 8276.4 8282.5 847.5 848.5 924.8 975.9 1094.1 24335.6 27909.7 28220.0 29406.3 30217.7 994.8 1049.3 1065.7 1065.7 1107.8 14759.3 15320.9 15355.7 15608.4 15704.0 668848.0 671622.0 671650.0 672357.0 673240.0 57781.7 58320.0 58417.5 58426.7 58646.0 1305.3 1349.5 1364.4 1366.7 1367.8 28152.4 29014.6 29108.3 29797.9 29851.8 51120.0 51348.0 51468.0 51556.7 51624.5 489822.0 493760.0 494002.0 494803.0 494839.0 41278.2 41400.2 41994.6 42008.0 42265.0 44464.6 45619.0 46478.0 46527.5 47483.5 32429.0 35921.5 37909.1 38213.6 39293.4 38999.6 41278.6 42111.8 42395.4 42565.8 34571.8 38662.8 38981.2 39820.2 40855.0 30787.7 39708.8 40376.0 42111.8 42248.8 29675.9 30105.1 30984.0 31103.7 31665.3 01:02:27 INFO - PROCESS | 1635 | 01:02:27 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:34159.30 Median:24880.06 stddev:94964.95 (381.7%) stddev-sans-first:95197.42 01:02:27 INFO - PROCESS | 1635 | Values: 346.5 369.1 369.2 382.4 398.9 12815.6 14483.3 15672.8 19781.6 20339.0 19097.8 19187.0 19548.5 19727.5 19745.0 5029.6 5251.6 5262.7 5266.8 5271.2 4691.0 5155.9 5176.7 5199.4 5214.8 5207.9 5214.2 5219.8 5223.9 5226.7 5131.0 5218.7 5639.3 5681.8 5695.2 4928.6 5052.5 5058.3 5059.8 5060.7 12829.4 16366.8 23343.9 24745.9 25014.2 2134.1 2147.7 2193.7 2225.1 2258.6 9734.9 9804.0 9947.9 9987.5 10137.5 1792.5 1805.1 1815.9 1913.6 1938.0 123377.0 126943.0 129068.4 129434.1 129889.0 10989.3 11371.9 11386.0 11403.6 11487.7 11635.0 13514.4 14343.7 14350.4 14356.4 6787.8 7057.8 8354.7 8358.0 8499.9 8016.2 8111.5 8182.5 8182.6 8368.5 4409.0 4418.1 4428.0 4436.9 4514.0 1804.9 1824.9 1856.7 1892.8 1896.4 722.7 832.3 908.5 923.6 947.8 19843.0 21247.0 21352.5 21442.6 21577.6 882.7 912.5 935.2 972.7 1000.6 10586.6 11026.1 11136.0 11173.4 11197.0 549091.0 557057.2 561366.0 562965.0 586893.1 129971.0 132487.0 134968.0 135808.0 137088.3 939.7 944.9 970.6 1013.4 1014.1 18885.3 19626.7 19649.5 19649.7 19659.6 48734.5 53432.0 53736.5 54735.5 55023.7 77470.6 77974.0 79691.4 82065.6 86600.6 7004.2 7224.8 7346.2 7500.1 7875.4 11497.0 12893.1 12929.5 13090.1 13327.1 10892.5 10948.9 11368.2 11604.8 11638.2 8951.8 10873.9 11161.4 11513.7 11576.6 11067.4 11237.6 11808.1 11835.0 11837.8 7319.7 7442.0 7504.8 7537.0 7570.8 9119.4 10859.6 10944.6 11198.0 11552.8 01:02:27 INFO - PROCESS | 1635 | 01:02:27 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5323.20 Median:5107.25 stddev:2935.29 (57.5%) stddev-sans-first:2928.07 01:02:27 INFO - PROCESS | 1635 | Values: 1316.1 1495.0 1513.4 1682.6 1847.4 7364.1 7781.5 7842.8 8191.4 8209.3 7920.6 8215.6 8222.4 8222.8 8226.7 2252.2 2310.6 2311.2 2313.5 2315.9 2282.6 2322.0 2331.6 2339.3 2341.4 1967.7 2273.0 2273.8 2299.9 2302.9 2224.1 2255.2 2286.3 2287.8 2288.3 2289.8 2307.1 2311.8 2315.4 2316.6 8080.2 8243.9 8255.3 8277.8 8280.2 4904.0 5105.3 5109.2 5131.1 5138.1 3093.2 3288.5 3454.8 3509.5 3569.8 1020.6 1057.9 1071.0 1088.2 1097.8 10626.7 10676.8 10814.2 10827.6 10829.1 4422.3 4503.3 4520.1 4588.1 4594.6 9351.5 9365.1 9374.1 9386.2 9451.0 5135.4 5142.3 5147.8 5155.0 5168.8 3964.4 4201.7 4341.2 4363.7 4616.2 3724.9 3764.5 3835.9 3859.3 3864.5 1217.6 1240.6 1242.1 1246.5 1251.9 867.5 872.0 882.3 909.9 914.5 5343.8 5528.2 5628.7 5674.8 5750.7 438.7 447.4 448.8 451.3 924.4 3340.6 3673.3 3767.1 3815.6 3875.7 9143.1 9646.1 9924.2 9938.2 10097.5 9956.3 10054.2 10060.7 10061.9 10096.2 6516.6 7507.8 7631.4 7787.7 7801.2 5339.6 5393.9 5444.5 5502.9 5677.3 9229.2 9250.6 9263.0 9315.0 9334.6 8527.4 8872.0 9087.3 9557.0 9586.5 6279.5 6455.0 6604.8 6678.6 6739.9 7687.4 7809.3 7874.7 7893.1 7904.3 5567.0 6028.7 6178.6 7000.6 7098.6 6814.3 6888.1 6919.7 6942.0 6972.4 6456.6 6583.0 6587.4 6590.7 6600.4 4666.9 4800.7 4815.9 4851.4 4918.2 4685.0 4789.1 4821.6 4855.3 4894.8 01:02:27 INFO - PROCESS | 1635 | 01:02:27 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4042.35 Median:4424.22 stddev:1186.56 (26.8%) stddev-sans-first:1156.05 01:02:27 INFO - PROCESS | 1635 | Values: 293.6 298.9 305.7 311.1 313.9 4832.7 4985.6 5008.3 5040.7 5070.3 4245.0 4850.4 4899.3 4908.5 4944.5 3840.0 3859.4 3877.6 3885.8 3933.1 2820.9 3771.8 3847.3 3901.3 3975.7 3850.5 3859.2 3859.8 3871.2 3905.2 3464.0 3834.7 3841.4 3855.2 3858.1 2806.8 3148.0 3373.3 3373.7 3404.6 4010.4 4215.2 4368.9 4850.9 4896.5 4065.0 4353.3 4416.8 4428.4 4450.5 4189.8 4899.0 5144.6 5198.0 5205.3 3733.2 3735.4 3737.5 3738.8 3739.7 4362.0 4604.9 5157.6 5366.6 5466.0 3070.4 3200.0 3694.9 3750.9 3757.6 3354.7 3793.7 3809.2 3844.4 3845.7 4081.6 4347.1 4399.5 4409.9 4413.8 4823.8 4982.3 5271.4 5289.4 5292.9 971.0 972.4 1081.0 1108.7 1181.5 755.7 842.3 847.5 864.8 865.7 3876.7 4060.4 4555.7 4557.0 4595.9 4373.1 4421.6 4584.4 4776.0 4918.4 4255.7 4290.8 4356.2 4574.8 4597.6 2304.9 2497.7 2593.3 2630.6 2666.6 4164.9 4370.5 4372.8 5139.9 5205.9 4322.2 4703.5 4846.2 4883.4 5178.5 4668.7 5192.3 5194.7 5233.3 5237.0 4310.9 4415.2 4775.4 4970.9 4992.7 4577.2 4825.0 4958.0 4976.5 5014.0 4384.6 4651.0 4780.4 4996.0 5174.6 3169.3 3450.5 3511.0 3524.9 3539.8 4295.3 4721.3 4780.2 5000.8 5016.5 4576.9 4803.0 4837.4 4859.5 4891.8 3947.9 4344.9 4490.7 4834.4 4874.9 4433.9 4633.8 4738.2 4742.2 4762.7 4520.4 4656.7 4789.4 4856.6 4877.3 3605.6 4392.5 4426.9 4560.7 4566.2 01:02:27 INFO - PROCESS | 1635 | 01:02:27 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:19744.27 Median:4370.84 stddev:28472.03 (651.4%) stddev-sans-first:28516.04 01:02:27 INFO - PROCESS | 1635 | Values: 713.0 730.4 759.9 783.2 796.6 14473.3 15127.0 15150.3 15162.3 15189.9 12409.8 12481.8 12483.1 12490.1 12500.6 674.7 696.1 822.8 831.4 859.5 840.8 844.4 846.3 846.3 853.0 898.3 901.0 903.5 904.6 906.4 893.5 894.9 896.1 896.9 898.6 514.2 538.8 542.2 544.3 545.6 12979.4 13537.1 13544.3 13830.7 13851.4 11236.8 13147.4 13497.5 13529.0 13628.4 10158.8 10439.3 11049.4 11137.2 11563.5 3643.7 3665.8 3666.0 3696.5 3796.1 84379.6 85226.0 85705.1 85817.3 86575.0 10271.5 10401.5 10549.5 10754.9 10782.6 19787.9 19884.6 20385.5 20420.1 20434.1 7580.3 7661.8 7686.3 7845.2 7890.6 4230.4 4243.9 4259.5 4292.0 4377.3 3362.6 3482.7 3485.0 3492.2 3492.8 638.1 652.5 653.8 660.3 660.6 638.5 643.7 658.7 668.7 683.3 8581.5 8799.0 8914.2 8971.1 9099.4 567.2 575.5 601.0 613.3 614.3 3521.5 4081.9 4333.4 4364.4 4591.3 77199.6 81432.0 107880.8 110236.4 113935.0 64009.7 77770.6 77921.6 108206.0 111555.0 78303.6 86022.1 86474.0 86687.7 88488.6 9294.3 9493.3 9604.4 9677.7 9697.6 53823.7 55502.0 55604.2 57811.7 59058.4 53006.7 63257.4 67837.3 74201.0 77686.2 9457.7 12601.7 12723.4 12872.6 13620.1 13825.0 13843.4 14173.6 14209.4 14796.4 12725.2 12865.9 12948.9 13001.6 13016.0 12972.3 13022.6 13389.2 13416.3 13892.8 12233.9 12292.6 12473.8 13036.0 13572.9 10078.6 10114.2 10467.2 10591.5 10812.5 9944.7 10666.5 10727.6 10731.7 10983.0 01:02:27 INFO - PROCESS | 1635 | -------- Summary: end -------- 01:02:27 INFO - PROCESS | 1635 | 01:02:27 INFO - TEST-INFO | 1635: exit 0 01:02:27 INFO - TEST-OK | dromaeo_css | took 1120680ms 01:02:27 INFO - TEST-START | kraken 01:02:27 INFO - operating with platform_type : mac_ 01:02:27 INFO - Initialising browser for kraken test... 01:02:27 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpsm7to5/profile http://localhost:49240/getInfo.html) 01:02:30 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 01:02:30 INFO - PROCESS | 1644 | colorDepth:24 01:02:30 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 01:02:30 INFO - PROCESS | 1644 | __metrics 01:02:30 INFO - PROCESS | 1644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:02:30 INFO - PROCESS | 1644 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:02:31 INFO - TEST-INFO | 1644: exit 0 01:02:31 INFO - Browser initialized. 01:02:31 INFO - Running cycle 1/1 for kraken test... 01:02:31 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpsm7to5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:02:43 INFO - PROCESS | 1645 | RSS: Main: 243298304 01:02:43 INFO - PROCESS | 1645 | 01:02:46 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 01:02:47 INFO - PROCESS | 1645 | RSS: Main: 294076416 01:02:47 INFO - PROCESS | 1645 | 01:02:50 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 01:02:50 INFO - PROCESS | 1645 | RSS: Main: 296579072 01:02:50 INFO - PROCESS | 1645 | 01:02:54 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 01:02:54 INFO - PROCESS | 1645 | RSS: Main: 386396160 01:02:54 INFO - PROCESS | 1645 | 01:02:56 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 01:02:57 INFO - PROCESS | 1645 | RSS: Main: 291778560 01:02:57 INFO - PROCESS | 1645 | 01:02:59 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:03:00 INFO - PROCESS | 1645 | RSS: Main: 294068224 01:03:00 INFO - PROCESS | 1645 | 01:03:03 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 01:03:03 INFO - PROCESS | 1645 | RSS: Main: 335925248 01:03:03 INFO - PROCESS | 1645 | 01:03:06 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 01:03:07 INFO - PROCESS | 1645 | RSS: Main: 338911232 01:03:07 INFO - PROCESS | 1645 | 01:03:09 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 01:03:10 INFO - PROCESS | 1645 | RSS: Main: 336224256 01:03:10 INFO - PROCESS | 1645 | 01:03:12 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:03:12 INFO - PROCESS | 1645 | RSS: Main: 309063680 01:03:12 INFO - PROCESS | 1645 | 01:03:14 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:03:15 INFO - PROCESS | 1645 | RSS: Main: 311246848 01:03:15 INFO - PROCESS | 1645 | 01:03:17 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:03:18 INFO - PROCESS | 1645 | RSS: Main: 313061376 01:03:18 INFO - PROCESS | 1645 | 01:03:21 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:03:22 INFO - PROCESS | 1645 | RSS: Main: 330887168 01:03:22 INFO - PROCESS | 1645 | 01:03:24 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:03:25 INFO - PROCESS | 1645 | RSS: Main: 324952064 01:03:25 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 01:03:27 INFO - PROCESS | 1645 | RSS: Main: 368275456 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | __start_tp_report 01:03:27 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 01:03:27 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 01:03:27 INFO - PROCESS | 1645 | |i|pagename|runs| 01:03:27 INFO - PROCESS | 1645 | |0;ai-astar;112;114;113;114;115;114;114;117;161;134 01:03:27 INFO - PROCESS | 1645 | |1;audio-beat-detection;147;112;117;147;131;141;140;112;137;110 01:03:27 INFO - PROCESS | 1645 | |2;audio-dft;184;184;193;264;184;185;183;182;175;182 01:03:27 INFO - PROCESS | 1645 | |3;audio-fft;79;57;58;87;59;57;56;61;82;57 01:03:27 INFO - PROCESS | 1645 | |4;audio-oscillator;90;70;94;71;82;100;127;97;96;92 01:03:27 INFO - PROCESS | 1645 | |5;imaging-gaussian-blur;86;84;87;86;89;83;87;84;87;83 01:03:27 INFO - PROCESS | 1645 | |6;imaging-darkroom;97;93;93;94;95;93;93;94;92;93 01:03:27 INFO - PROCESS | 1645 | |7;imaging-desaturate;87;84;84;87;86;87;89;84;88;88 01:03:27 INFO - PROCESS | 1645 | |8;json-parse-financial;54;51;52;53;52;52;54;54;52;53 01:03:27 INFO - PROCESS | 1645 | |9;json-stringify-tinderbox;63;71;70;75;71;68;69;70;70;70 01:03:27 INFO - PROCESS | 1645 | |10;stanford-crypto-aes;88;87;86;86;85;97;81;108;106;107 01:03:27 INFO - PROCESS | 1645 | |11;stanford-crypto-ccm;146;135;168;169;158;154;147;155;217;175 01:03:27 INFO - PROCESS | 1645 | |12;stanford-crypto-pbkdf2;173;170;173;173;171;170;171;175;171;173 01:03:27 INFO - PROCESS | 1645 | |13;stanford-crypto-sha256-iterative;63;84;90;89;70;65;67;67;67;66 01:03:27 INFO - PROCESS | 1645 | __end_tp_report 01:03:27 INFO - PROCESS | 1645 | __start_cc_report 01:03:27 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1943 01:03:27 INFO - PROCESS | 1645 | __end_cc_report 01:03:27 INFO - PROCESS | 1645 | __startTimestamp1479805407702__endTimestamp 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | ------- Summary: start ------- 01:03:27 INFO - PROCESS | 1645 | Number of tests: 14 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | [#0] ai-astar Cycles:10 Average:120.80 Median:114.50 stddev:15.50 (13.5%) stddev-sans-first:16.11 01:03:27 INFO - PROCESS | 1645 | Values: 112.0 114.0 113.0 114.0 115.0 114.0 114.0 117.0 161.0 134.0 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:138.50 stddev:15.14 (10.9%) stddev-sans-first:14.66 01:03:27 INFO - PROCESS | 1645 | Values: 147.0 112.0 117.0 147.0 131.0 141.0 140.0 112.0 137.0 110.0 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | [#2] audio-dft Cycles:10 Average:191.60 Median:184.00 stddev:25.81 (14.0%) stddev-sans-first:27.23 01:03:27 INFO - PROCESS | 1645 | Values: 184.0 184.0 193.0 264.0 184.0 185.0 183.0 182.0 175.0 182.0 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | [#3] audio-fft Cycles:10 Average:65.30 Median:60.00 stddev:12.21 (20.4%) stddev-sans-first:11.90 01:03:27 INFO - PROCESS | 1645 | Values: 79.0 57.0 58.0 87.0 59.0 57.0 56.0 61.0 82.0 57.0 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:91.00 stddev:16.20 (17.8%) stddev-sans-first:17.17 01:03:27 INFO - PROCESS | 1645 | Values: 90.0 70.0 94.0 71.0 82.0 100.0 127.0 97.0 96.0 92.0 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:2.01 (2.3%) stddev-sans-first:2.13 01:03:27 INFO - PROCESS | 1645 | Values: 86.0 84.0 87.0 86.0 89.0 83.0 87.0 84.0 87.0 83.0 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:93.50 stddev:1.42 (1.5%) stddev-sans-first:0.87 01:03:27 INFO - PROCESS | 1645 | Values: 97.0 93.0 93.0 94.0 95.0 93.0 93.0 94.0 92.0 93.0 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | [#7] imaging-desaturate Cycles:10 Average:86.40 Median:87.00 stddev:1.84 (2.1%) stddev-sans-first:1.94 01:03:27 INFO - PROCESS | 1645 | Values: 87.0 84.0 84.0 87.0 86.0 87.0 89.0 84.0 88.0 88.0 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:1.06 (2.0%) stddev-sans-first:1.01 01:03:27 INFO - PROCESS | 1645 | Values: 54.0 51.0 52.0 53.0 52.0 52.0 54.0 54.0 52.0 53.0 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:70.00 stddev:2.98 (4.3%) stddev-sans-first:1.94 01:03:27 INFO - PROCESS | 1645 | Values: 63.0 71.0 70.0 75.0 71.0 68.0 69.0 70.0 70.0 70.0 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | [#10] stanford-crypto-aes Cycles:10 Average:93.10 Median:86.00 stddev:10.40 (12.1%) stddev-sans-first:10.86 01:03:27 INFO - PROCESS | 1645 | Values: 88.0 87.0 86.0 86.0 85.0 97.0 81.0 108.0 106.0 107.0 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:162.40 Median:163.00 stddev:22.65 (13.9%) stddev-sans-first:23.23 01:03:27 INFO - PROCESS | 1645 | Values: 146.0 135.0 168.0 169.0 158.0 154.0 147.0 155.0 217.0 175.0 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.00 Median:173.00 stddev:1.63 (0.9%) stddev-sans-first:1.69 01:03:27 INFO - PROCESS | 1645 | Values: 173.0 170.0 173.0 173.0 171.0 170.0 171.0 175.0 171.0 173.0 01:03:27 INFO - PROCESS | 1645 | 01:03:27 INFO - PROCESS | 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.80 Median:68.50 stddev:10.52 (15.4%) stddev-sans-first:10.54 01:03:27 INFO - PROCESS | 1645 | Values: 63.0 84.0 90.0 89.0 70.0 65.0 67.0 67.0 67.0 66.0 01:03:27 INFO - PROCESS | 1645 | -------- Summary: end -------- 01:03:27 INFO - PROCESS | 1645 | 01:03:28 INFO - TEST-INFO | 1645: exit 0 01:03:28 INFO - TEST-OK | kraken | took 61141ms 01:03:28 INFO - SUITE-END | took 1181s 01:03:28 INFO - Completed test suite (00:19:41) 01:03:28 INFO - javascript benchmark 01:03:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2801.831890086596, 3042.391521695661, 3172.936541269173, 3290.30903510263, 3321.103302108431, 5500.752466139023, 5506.0089183946975, 5507.118860982244, 5528.253685752422, 5592.944070559293, 5534.08687566551, 5575.553955683546, 5583.162525621159, 5617.8033768818095, 5631.1271752878265, 4989.176785830334, 5073.771680274378, 5129.692218466886, 5140.768665410065, 5236.528712415882, 5189.792408303663, 5232.764525596357, 5237.0, 5243.082460569404, 5292.391374991887, 5063.41770696371, 5106.463821298785, 5187.948120518784, 5195.19474481456, 5196.246544251081, 5146.639735218536, 5153.484651534854, 5159.871003224912, 5161.148410512261, 5163.173892177234, 5081.263216833576, 5107.055194788976, 5110.156824124052, 5126.769295381717, 5129.384473863123, 5539.529140023103, 5584.692841893694, 5587.664740115606, 5594.104943209108, 5598.748056337475, 6002.189704389895, 6007.8197654070445, 6009.369016253274, 6011.939880601181, 6060.757569697251, 6008.729607167645, 6044.304904935901, 6159.384061593805, 6185.56701030928, 6188.721507532172, 4747.2879068140055, 5613.270274864323, 5618.101103823368, 5642.463965923313, 5689.743961521742, 5035.723035233024, 6285.088662143963, 6308.400701933309, 6312.652804095727, 6327.651979141191, 6052.576319657582, 6068.605540639845, 6070.514358851281, 6080.361562036011, 6080.361562036011, 6035.849103772353, 6051.364606716319, 6052.515798736091, 6095.329513753483, 6174.197354343906, 6041.697915104227, 6117.490066423026, 6118.9846826261555, 6129.981545214243, 6144.187464697925, 6130.459285710791, 6143.877122457526, 6148.538859585459, 6149.754009839646, 6161.291451483136, 4707.505711900201, 4815.133276010351, 4821.252705830602, 4822.107910036654, 4827.2035114206465, 3612.6387361263664, 4026.7181297308907, 4351.97824010878, 4378.64970802335, 4382.298832186835, 5560.193771903051, 5616.719164041781, 5646.040173170571, 5653.0, 5663.631863928831, 6019.518438524908, 6063.272407311151, 6153.230846144232, 6197.566170368121, 6207.689615519206, 5518.724063796794, 5535.557155427556, 5599.580031497654, 5601.271834661468, 5608.439156084359, 5277.23480095384, 5277.762500687479, 5291.285676433632, 5298.231756395301, 5309.0, 6219.968900155561, 6268.62388256697, 6280.8429789253805, 6286.119943208046, 6288.0882272069375, 5080.974595127001, 6107.572469927062, 6110.663913484801, 6115.4190351916495, 6129.785457508966, 6214.751409943552, 6239.844003899939, 6248.875022499434, 6250.843728906813, 6261.68691565429, 5914.674692891932, 5922.022866227197, 5924.11138329254, 5928.170056192051, 5930.406959304035, 5383.327084114486, 6137.171481849893, 6193.411625895533, 6255.812325630056, 6266.248050234001, 6057.4851137653795, 6058.0, 6062.636241825505, 6064.878702426015, 6067.939320606737, 5790.536757059341, 5860.824175274749, 5870.17817505541, 5937.0, 6011.428914253139, 5842.620229685057, 5847.327557330961, 5851.561132915133, 5863.472287494146, 5915.58590898633, 5881.38245484218, 5993.580449368503, 6002.939970600238, 6026.337102918763, 6050.818475445568, 5653.1802888580105, 5726.4273572642405, 5731.8853622928145, 5740.253767010262, 5827.679477628772], "value": 5565.503438972114, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.6268097479091, 468.7960737079373, 501.13554119144476, 513.6789506558401, 516.3622925686778, 30188.396232075374, 31953.721851125956, 39984.400233996515, 40995.38506922399, 41180.76457706266, 41799.58200417995, 42214.0, 42222.0, 42278.788606057, 42297.0, 9138.999999999984, 9327.207187389045, 9330.346875718702, 9331.813363732721, 9335.206507446876, 9321.580528876217, 9413.388129771578, 9417.529123543829, 9420.952895235514, 9421.387609805375, 9384.671536496224, 9442.527873606326, 9445.480498572575, 9449.338546301728, 9461.95269023654, 9328.253739700842, 9390.248780097643, 9402.200812930909, 9406.200472959737, 9412.764680882998, 9250.259979201648, 9337.859932101024, 9357.111074447917, 9361.531923403803, 9361.625534978593, 39459.21081578381, 39745.80127099375, 39764.403533947014, 39820.60179398198, 39872.80381588528, 3305.8512366943305, 3370.5281260623533, 3405.5743032120986, 3676.4117741161554, 3939.0940083780606, 29722.0, 30534.84732576345, 30637.234069148228, 30644.233894152603, 30794.84602576995, 3197.9680203197904, 3216.694414030687, 3245.432049391347, 3257.9022629321157, 3350.798952062884, 363014.0, 479406.0, 485641.0, 486414.0, 493906.5304673454, 61432.77134457286, 61924.38075619186, 61945.690271549254, 62163.37836621576, 62207.6889615549, 67530.66234668795, 69752.30247697458, 69865.30134698689, 69904.65047674831, 73430.63284683542, 14919.40322387114, 15059.924700376429, 15368.154751488551, 15372.000819946668, 15382.692346153239, 12262.938685306517, 12386.690332741573, 12411.007119430604, 12412.317322547166, 12422.130450868533, 19610.215591376185, 20141.0, 20188.39434816958, 20199.596008079756, 20204.898975505323, 8126.0, 8193.385496087818, 8232.39468303687, 8276.379271554657, 8282.50304981703, 847.5211505499373, 848.5036253791478, 924.7734305095295, 975.9024097590184, 1094.0919037199105, 24335.634965475532, 27909.720902790712, 28220.01229956942, 29406.264843379086, 30217.69782302193, 994.8470497125644, 1049.275999560301, 1065.6696424108552, 1065.6962765611918, 1107.828286615576, 14759.262036898326, 15320.92753507266, 15355.692886142215, 15608.3756649735, 15703.979241349276, 668848.0, 671622.0, 671650.0, 672357.0, 673240.0, 57781.711091444275, 58320.0, 58417.450342217424, 58426.7078664604, 58646.0, 1305.2755720574983, 1349.5344106283317, 1364.3996641477718, 1366.7403193393222, 1367.8085068090475, 28152.43695126127, 29014.56478152787, 29108.27229319284, 29797.85101074481, 29851.775939210118, 51120.0, 51348.0, 51468.0, 51556.74221628868, 51624.48375516197, 489822.0, 493760.0, 494002.0, 494803.0, 494839.0, 41278.174436510504, 41400.1719965605, 41994.58005420029, 42008.0, 42265.0, 44464.55535444604, 45619.0, 46478.0, 46527.53472465232, 47483.52516474791, 32429.02712918616, 35921.46117808287, 37909.05227369227, 38213.61786382212, 39293.41059884161, 38999.6100038996, 41278.587214128675, 42111.7894410526, 42395.364069538366, 42565.787171063945, 34571.82714086414, 38662.80668596639, 38981.22037559289, 39820.2035959285, 40854.9786255346, 30787.692123078483, 39708.801455992536, 40376.0, 42111.7894410526, 42248.78875605603, 29675.85162074176, 30105.09684709462, 30984.0, 31103.68896311008, 31665.25841078763], "value": 20257.60745025109, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.46990105139145, 369.123332086295, 369.2209438085639, 382.4149051950516, 398.94015897615355, 12815.615531534046, 14483.275836208188, 15672.764908526367, 19781.604367912634, 20339.0, 19097.809021909776, 19187.0, 19548.511287217825, 19727.5068123297, 19745.0, 5029.597632189416, 5251.5798736101215, 5262.710550919698, 5266.762995665185, 5271.15661494161, 4691.0, 5155.871103222431, 5176.663516871391, 5199.376074871002, 5214.843554693346, 5207.895842083156, 5214.165733482643, 5219.765110570014, 5223.895522089556, 5226.660267082647, 5130.999999999981, 5218.660787048849, 5639.323281206241, 5681.829545113653, 5695.202671625934, 4928.630352723524, 5052.520010598987, 5058.291839142523, 5059.848204553869, 5060.721660308682, 12829.358532073358, 16366.772492063143, 23343.88328058349, 24745.876270618533, 25014.249572512854, 2134.1036764558767, 2147.656374980011, 2193.7257842769654, 2225.109956017606, 2258.6047441697638, 9734.853977190347, 9804.0, 9947.850782238273, 9987.500624968796, 10137.493125343777, 1792.5160206744235, 1805.124514610413, 1815.936442224529, 1913.6268427656612, 1937.9515512112168, 123377.0, 126943.0, 129068.35465822705, 129434.05848912368, 129889.0, 10989.340639561651, 11371.943140284246, 11386.032187264009, 11403.600873969372, 11487.71280717972, 11635.0, 13514.391852366667, 14343.713125737428, 14350.354234059492, 14356.353964071643, 6787.796366109024, 7057.788266352018, 8354.749357519284, 8357.958210209032, 8499.872501912352, 8016.198380161937, 8111.513309201467, 8182.509049457051, 8182.590870456454, 8368.539730314891, 4409.030013397048, 4418.138462999685, 4427.955720442755, 4436.911261774746, 4513.977430112894, 1804.900730459811, 1824.9087545622665, 1856.7281412232664, 1892.8391086757504, 1896.3552392186723, 722.6964674836581, 832.3216578488513, 908.504864848659, 923.5520772425368, 947.8104379124203, 19843.0, 21247.0, 21352.466188345104, 21442.571148576943, 21577.590480912248, 882.7440042387764, 912.4570880326195, 935.1577392536692, 972.6917296236551, 1000.6447711062614, 10586.629467968738, 11026.117910567136, 11135.993281813864, 11173.441327933571, 11196.992270695677, 549091.0, 557057.2147139319, 561366.0, 562965.0, 586893.0655346696, 129971.0, 132487.0, 134968.0, 135807.96288055688, 137088.31455842656, 939.7133874168308, 944.9338546301831, 970.5777986575862, 1013.366645846346, 1014.1278500489573, 18885.339013134475, 19626.705599415734, 19649.508762280486, 19649.70525442091, 19659.60680786405, 48734.512654873, 53432.0, 53736.462635373144, 54735.45264547304, 55023.72488137534, 77470.61264693641, 77974.0, 79691.4061718774, 82065.58967205125, 86600.56699716461, 7004.194517630538, 7224.819379515554, 7346.191918888811, 7500.062492188476, 7875.448718589873, 11497.0, 12893.097483176087, 12929.482820687068, 13090.14914030585, 13327.067105302536, 10892.509837057192, 10948.890511094787, 11368.204225704121, 11604.825927610924, 11638.185327027028, 8951.776205594912, 10873.891261087289, 11161.441927903736, 11513.654590362303, 11576.594819181626, 11067.391293478937, 11237.550497980315, 11808.114391420506, 11835.0, 11837.810005149075, 7319.670614822239, 7442.032535770315, 7504.774856754306, 7536.962315188389, 7570.810729731801, 9119.361644684808, 10859.565617375216, 10944.562217511211, 11198.04816590599, 11552.82670759956], "value": 9199.03824911468, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.0655934286656, 1494.9551513454594, 1513.3643869574778, 1682.5709444091756, 1847.4088291746639, 7364.1163060432755, 7781.455298129126, 7842.843143137141, 8191.426600137993, 8209.261166495015, 7920.564368959705, 8215.630296636651, 8222.383321250914, 8222.753317400473, 8226.670933162666, 2252.1892118837245, 2310.5905397707384, 2311.225739377302, 2313.53729254149, 2315.8957846896847, 2282.589133955887, 2322.0, 2331.5803155432013, 2339.333290012347, 2341.379534423379, 1967.6753335699593, 2272.9545409091807, 2273.8408311418207, 2299.908003679859, 2302.8618282902994, 2224.077008041661, 2255.1655887321795, 2286.302677683306, 2287.782660647236, 2288.347820871044, 2289.771022897697, 2307.111761971636, 2311.780380863815, 2315.4211447138214, 2316.5598536278053, 8080.191980801932, 8243.876341854877, 8255.25702686761, 8277.83444331116, 8280.213379728915, 4903.975480122576, 5105.259737338065, 5109.233614957749, 5131.050755610187, 5138.100832354323, 3093.2266933266683, 3288.490284005983, 3454.844531996041, 3509.4560343146854, 3569.785812851237, 1020.6274709730969, 1057.9365238085663, 1071.0, 1088.1566785741113, 1097.7639807441437, 10626.681199564026, 10676.839847402296, 10814.188935829845, 10827.621033263798, 10829.133669306437, 4422.270325396338, 4503.2569626012, 4520.050789334211, 4588.128255631419, 4594.632429405639, 9351.48566828831, 9365.11031452011, 9374.062593740708, 9386.249100071978, 9451.0, 5135.435102138762, 5142.254373115878, 5147.819826306061, 5155.0, 5168.767405466763, 3964.385520244367, 4201.705880588391, 4341.19687857741, 4363.650907927358, 4616.215243408628, 3724.888253352404, 3764.5294338207723, 3835.9041023974623, 3859.3439115350457, 3864.478295430081, 1217.6103646833105, 1240.5968060380412, 1242.1118899986552, 1246.4764798784531, 1251.9436625351882, 867.509856930832, 871.9694810681723, 882.2589025218847, 909.8666454992756, 914.5061666700041, 5343.759530821122, 5528.154744843014, 5628.746706398222, 5674.779922316639, 5750.712464376848, 438.6600384701818, 447.35804121086346, 448.7800977520991, 451.27570249749334, 924.4458521183642, 3340.5991281045945, 3673.3204357194004, 3767.1335592813807, 3815.5802861685193, 3875.670568001695, 9143.13140251675, 9646.083622055894, 9924.156446701967, 9938.204943604494, 10097.495125243855, 9956.253281003954, 10054.245931555162, 10060.69817905464, 10061.899381006097, 10096.242781791396, 6516.641584712792, 7507.812304692427, 7631.3894888408795, 7787.688492460352, 7801.180876008051, 5339.626226164131, 5393.865153371197, 5444.482774136451, 5502.862428439321, 5677.347105082803, 9229.215516680888, 9250.583723732314, 9263.0, 9314.95342523283, 9334.626614935598, 8527.403081784216, 8871.955640221757, 9087.273018158663, 9557.0, 9586.520673966133, 6279.529035322278, 6455.0, 6604.8348791280605, 6678.632675202716, 6739.898901516579, 7687.3570502094035, 7809.33620642229, 7874.72438464651, 7893.092294386219, 7904.328132108835, 5567.0, 6028.728707208274, 6178.62928224308, 7000.649967501707, 7098.609576473344, 6814.250432452327, 6888.138982627172, 6919.688614012281, 6941.965290173517, 6972.4073453757, 6456.612603243821, 6583.0454584086, 6587.440067594308, 6590.670466476561, 6600.372964568358, 4666.929996049994, 4800.687955282895, 4815.879603009953, 4851.393575803025, 4918.2376731606655, 4684.953150468452, 4789.066132104345, 4821.63837712166, 4855.271709243641, 4894.755262236803], "value": 4276.547214627842, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.60216906092245, 298.9297515083955, 305.70652173913044, 311.08540889784035, 313.8807253243767, 4832.71003739775, 4985.576226020793, 5008.32054850655, 5040.697558146514, 5070.264811602316, 4244.957550424495, 4850.442199147099, 4899.31409602655, 4908.50914908509, 4944.5055494450535, 3839.9824046627664, 3859.421086836983, 3877.612238776128, 3885.7668539887554, 3933.0727943307716, 2820.9012684556046, 3771.849126034955, 3847.2882516734353, 3901.31726947783, 3975.7216994810374, 3850.4994350734505, 3859.1895701902636, 3859.8456061757497, 3871.187050719352, 3905.2189562087556, 3463.9826800866085, 3834.657869745595, 3841.385378339466, 3855.209682015194, 3858.054776579756, 2806.8456234907276, 3148.0, 3373.26276967362, 3373.7301015918915, 3404.6254911959663, 4010.3984402339593, 4215.220184265921, 4368.929612244989, 4850.927236091461, 4896.534829191221, 4064.9837540614844, 4353.25994580922, 4416.823327066913, 4428.446444194476, 4450.46594408673, 4189.790510474494, 4898.975505122452, 5144.588432925394, 5198.038362902837, 5205.349331333583, 3733.234686889208, 3735.383661695817, 3737.4767532545466, 3738.7569807962395, 3739.719521035906, 4362.0, 4604.907901841944, 5157.5616072633875, 5366.624336296461, 5465.988792073439, 3070.4012717519868, 3199.9520007199912, 3694.9445758313645, 3750.9182403765626, 3757.6242375762204, 3354.6645335466746, 3793.677537409296, 3809.1810260794064, 3844.442555829389, 3845.731018698981, 4081.591840815954, 4347.130733784503, 4399.472063352418, 4409.927334153007, 4413.7793110345, 4823.831165909176, 4982.277569752366, 5271.446498117719, 5289.418164001957, 5292.941411717672, 970.9562220612869, 972.4262685015875, 1081.0000749307017, 1108.7283615514089, 1181.4565299962114, 755.7317152410925, 842.2924743215729, 847.4576271186446, 864.7708357285325, 865.7056600755773, 3876.651101400831, 4060.4315395844606, 4555.726656400627, 4556.954430455653, 4595.931061034087, 4373.059792144704, 4421.579949904753, 4584.381108550369, 4775.95224047762, 4918.360613120343, 4255.659547236213, 4290.841472802326, 4356.215881141425, 4574.771261436981, 4597.563231493003, 2304.873231972224, 2497.7002759668594, 2593.338698631837, 2630.631711560383, 2666.600009998477, 4164.876342742943, 4370.454727083861, 4372.813593203398, 5139.939447836167, 5205.9393672935175, 4322.22200003996, 4703.459102203222, 4846.224604063333, 4883.3651625288485, 5178.508041736029, 4668.688998945476, 5192.324997750268, 5194.714290714047, 5233.319668443154, 5237.019864902625, 4310.913781724411, 4415.22733521626, 4775.355327030807, 4970.8757281068265, 4992.72540010303, 4577.198990176772, 4824.962633033914, 4957.950420495749, 4976.477469865612, 5013.997200559829, 4384.605385515319, 4650.9767451162525, 4780.426348838162, 4996.0, 5174.586033117266, 3169.286910445224, 3450.46517789743, 3511.0, 3524.8766293179615, 3539.823008849599, 4295.334223195409, 4721.315409265706, 4780.1873681473535, 5000.774965126505, 5016.473270306711, 4576.8626941191815, 4802.975985120052, 4837.37114175155, 4859.47307400157, 4891.779869905934, 3947.8618248361167, 4344.934825977676, 4490.685652004328, 4834.371531700997, 4874.926876096933, 4433.889152771207, 4633.8146474142, 4738.170820106537, 4742.217534106834, 4762.714067201905, 4520.412346394947, 4656.650751193606, 4789.449213340371, 4856.63575231852, 4877.317175595348, 3605.6033836277466, 4392.450943632046, 4426.867193984185, 4560.67405623128, 4566.178087944069], "value": 3668.206670810456, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.0, 730.3718801830424, 759.9164091949885, 783.2363445640498, 796.5539297993123, 14473.34869930853, 15127.016743911638, 15150.318235679393, 15162.317695703692, 15189.936704430695, 12409.813852792215, 12481.750364992695, 12483.126181167321, 12490.12569120162, 12500.624981250554, 674.6626686656672, 696.1402667705405, 822.847773161967, 831.3764676492631, 859.5401460218784, 840.7645859159445, 844.4468872888268, 846.2595229174473, 846.3398549131687, 852.9744107676779, 898.27239935652, 900.9909900900973, 903.4850135422812, 904.6245807989676, 906.3700727994046, 893.5264309915723, 894.932880034, 896.12627688004, 896.8699538566872, 898.6360523987795, 514.2286570144782, 538.8464287677992, 542.2435702195415, 544.28154835617, 545.5662748115258, 12979.41592628334, 13537.1200871943, 13544.322783860865, 13830.65423364424, 13851.376688049062, 11236.763955964838, 13147.408366623526, 13497.527586534523, 13529.0, 13628.386722597506, 10158.847617285746, 10439.269251152422, 11049.392283424408, 11137.164712646583, 11563.479643416067, 3643.708503319728, 3665.798381089039, 3665.963340366589, 3696.464012718141, 3796.1079146400575, 84379.5781021091, 85226.0, 85705.14294857034, 85817.28365432781, 86575.0, 10271.486425678686, 10401.53193106312, 10549.47252637365, 10754.946225268981, 10782.622608208674, 19787.911664858282, 19884.565071946967, 20385.49036274105, 20420.081096350405, 20434.080466379048, 7580.317771400601, 7661.846763064707, 7686.3082322591235, 7845.215478452109, 7890.605469726491, 4230.428892099589, 4243.872683819491, 4259.531451540328, 4291.957080429156, 4377.299632058855, 3362.5628668272966, 3482.7213822894614, 3485.0, 3492.1793378556017, 3492.790432574003, 638.090720722968, 652.5301782716436, 653.7809833705762, 660.3363619562405, 660.6168422315046, 638.5370606310481, 643.7457204404234, 658.720043981306, 668.66144068845, 683.255251775567, 8581.485110893367, 8799.0, 8914.242289405473, 8971.058038905821, 9099.35839779341, 567.1690972724896, 575.5251917168353, 600.9849753756104, 613.2953674296332, 614.3395849461757, 3521.4717792331357, 4081.8775436736946, 4333.414988976511, 4364.388985542027, 4591.334256532784, 77199.61400192963, 81432.0, 107880.8423831519, 110236.448817757, 113935.0, 64009.679951600876, 77770.61114694504, 77921.61039194767, 108206.0, 111555.0, 78303.60848195723, 86022.13977860266, 86474.0, 86687.69968450605, 88488.55755721309, 9294.349395542518, 9493.288003399635, 9604.423734575948, 9677.661281855102, 9697.563609637724, 53823.73088134534, 55502.0, 55604.173007169615, 57811.710941446705, 59058.40941590701, 53006.73496632492, 63257.367426325145, 67837.32162678508, 74201.0, 77686.2231377679, 9457.668981585886, 12601.747965040464, 12723.427445764777, 12872.613821585368, 13620.114692544952, 13825.0, 13843.377048033064, 14173.645658858612, 14209.431622734975, 14796.408143674564, 12725.172863763826, 12865.938056565543, 12948.870511294766, 13001.609951701465, 13015.958723302303, 12972.28652424088, 13022.609321720363, 13389.19664820114, 13416.32918354098, 13892.791608125684, 12233.877661223274, 12292.631221063382, 12473.812892806796, 13036.0, 13572.86427135716, 10078.605570958589, 10114.190864730952, 10467.214958877963, 10591.5233814477, 10812.513436895515, 9944.651937182163, 10666.520006599567, 10727.624533141303, 10731.678049658523, 10983.011528962432], "value": 7173.423516303313, "unit": "score"}], "name": "dromaeo_css", "value": 6990.804613069641, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 113.0, 114.0, 115.0, 114.0, 114.0, 117.0, 161.0, 134.0], "value": 120.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 112.0, 117.0, 147.0, 131.0, 141.0, 140.0, 112.0, 137.0, 110.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 193.0, 264.0, 184.0, 185.0, 183.0, 182.0, 175.0, 182.0], "value": 191.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 58.0, 87.0, 59.0, 57.0, 56.0, 61.0, 82.0, 57.0], "value": 65.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 94.0, 71.0, 82.0, 100.0, 127.0, 97.0, 96.0, 92.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 86.0, 89.0, 83.0, 87.0, 84.0, 87.0, 83.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 93.0, 93.0, 94.0, 95.0, 93.0, 93.0, 94.0, 92.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 84.0, 87.0, 86.0, 87.0, 89.0, 84.0, 88.0, 88.0], "value": 86.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 52.0, 53.0, 52.0, 52.0, 54.0, 54.0, 52.0, 53.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 71.0, 70.0, 75.0, 71.0, 68.0, 69.0, 70.0, 70.0, 70.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 86.0, 86.0, 85.0, 97.0, 81.0, 108.0, 106.0, 107.0], "value": 93.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 135.0, 168.0, 169.0, 158.0, 154.0, 147.0, 155.0, 217.0, 175.0], "value": 162.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 170.0, 173.0, 173.0, 171.0, 170.0, 171.0, 175.0, 171.0, 173.0], "value": 172.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 84.0, 90.0, 89.0, 70.0, 65.0, 67.0, 67.0, 67.0, 66.0], "value": 72.8, "unit": "score"}], "name": "kraken", "value": 1487.4, "alertThreshold": 2.0}]} 01:03:28 INFO - Return code: 0 01:03:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:03:29 INFO - # TBPL SUCCESS # 01:03:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:03:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:03:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:03:29 INFO - 2016-11-22 01:03:29.031 screenresolution[1649:9681] starting screenresolution argv=screenresolution get 01:03:29 INFO - 2016-11-22 01:03:29.034 screenresolution[1649:9681] Display 0: 1600x1200x32@60 01:03:29 INFO - 2016-11-22 01:03:29.041 screenresolution[1650:9684] starting screenresolution argv=screenresolution list 01:03:29 INFO - Available Modes on Display 0 01:03:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:03:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:03:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:03:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:03:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:03:29 INFO - Intel Iris: 01:03:29 INFO - Chipset Model: Intel Iris 01:03:29 INFO - Type: GPU 01:03:29 INFO - Bus: Built-In 01:03:29 INFO - VRAM (Dynamic, Max): 1536 MB 01:03:29 INFO - Vendor: Intel (0x8086) 01:03:29 INFO - Device ID: 0x0a2e 01:03:29 INFO - Revision ID: 0x0009 01:03:29 INFO - Displays: 01:03:29 INFO - Display: 01:03:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:03:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:03:29 INFO - Display Serial Number: 1600x1200 60 01:03:29 INFO - Main Display: Yes 01:03:29 INFO - Mirror: Off 01:03:29 INFO - Online: Yes 01:03:29 INFO - Rotation: Supported 01:03:29 INFO - Return code: 0 01:03:29 INFO - Running post-action listener: _resource_record_post_action 01:03:29 INFO - [mozharness: 2016-11-22 09:03:29.287024Z] Finished run-tests step (success) 01:03:29 INFO - Running post-run listener: _resource_record_post_run 01:03:29 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 38966272; Write bytes: 693325824; Read time: 443; Write time: 2047 01:03:29 INFO - TinderboxPrint: CPU usage
53.9% 01:03:29 INFO - TinderboxPrint: I/O read bytes / time
38,966,272 / 443 01:03:29 INFO - TinderboxPrint: I/O write bytes / time
693,325,824 / 2,047 01:03:29 INFO - TinderboxPrint: CPU idle
2,239.9 (46.1%) 01:03:29 INFO - TinderboxPrint: CPU system
977.0 (20.1%) 01:03:29 INFO - TinderboxPrint: CPU user
1,644.8 (33.8%) 01:03:29 INFO - TinderboxPrint: Swap in / out
421,650,432 / 0 01:03:29 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 384403968; Write bytes: 385631744; Read time: 29216; Write time: 745 01:03:29 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 34554880; Write bytes: 306354688; Read time: 344; Write time: 1297 01:03:29 INFO - Running post-run listener: _upload_blobber_files 01:03:29 INFO - Blob upload gear active. 01:03:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:03:29 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 01:03:29 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:03:29 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:03:30 INFO - (blobuploader) - INFO - Open directory for files ... 01:03:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:03:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:03:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:03:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:03:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:03:31 INFO - (blobuploader) - INFO - Done attempting. 01:03:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:03:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:03:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:03:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:03:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:03:32 INFO - (blobuploader) - INFO - Done attempting. 01:03:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:03:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:03:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:03:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:03:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:03:34 INFO - (blobuploader) - INFO - Done attempting. 01:03:34 INFO - (blobuploader) - INFO - Iteration through files over. 01:03:34 INFO - Return code: 0 01:03:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:03:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:03:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5f345899da39464830423fd3fcebde84ed0449ea69005bbdfa2e92349c2155272df5f7c5132c1754b4490feccac5890d5d876a2fd59d1878255b003207d65fe2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/445fb90f75bbf159f74e76f33017af1a79bbeb595f460ff7ca4c2c7ab7a20e76854aa059dc717cb592afd2274a693aeca3fe6ab3926430918f00f296405d0506", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d2953e7f3bfb423965e21c481cdf0bddbf5e2310f7746af9e81d16cb3d5b5d62deede06912c31532018ae61c1fbb0388d2e9f23e95b2612216a4b6ba09cfe2ce"} 01:03:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:03:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:03:34 INFO - Contents: 01:03:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5f345899da39464830423fd3fcebde84ed0449ea69005bbdfa2e92349c2155272df5f7c5132c1754b4490feccac5890d5d876a2fd59d1878255b003207d65fe2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/445fb90f75bbf159f74e76f33017af1a79bbeb595f460ff7ca4c2c7ab7a20e76854aa059dc717cb592afd2274a693aeca3fe6ab3926430918f00f296405d0506", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d2953e7f3bfb423965e21c481cdf0bddbf5e2310f7746af9e81d16cb3d5b5d62deede06912c31532018ae61c1fbb0388d2e9f23e95b2612216a4b6ba09cfe2ce"} 01:03:34 INFO - Running post-run listener: copy_logs_to_upload_dir 01:03:34 INFO - Copying logs to upload dir... 01:03:34 INFO - mkdir: /builds/slave/test/build/upload/logs 01:03:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.720036 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-22 01:03:34.600786) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 01:03:34.609318) ========= 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.Wmgagls9MT/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.gnj0n6JnkI/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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/Graphics/sha512/5f345899da39464830423fd3fcebde84ed0449ea69005bbdfa2e92349c2155272df5f7c5132c1754b4490feccac5890d5d876a2fd59d1878255b003207d65fe2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/445fb90f75bbf159f74e76f33017af1a79bbeb595f460ff7ca4c2c7ab7a20e76854aa059dc717cb592afd2274a693aeca3fe6ab3926430918f00f296405d0506", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d2953e7f3bfb423965e21c481cdf0bddbf5e2310f7746af9e81d16cb3d5b5d62deede06912c31532018ae61c1fbb0388d2e9f23e95b2612216a4b6ba09cfe2ce"} build_url:https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011128 build_url: 'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5f345899da39464830423fd3fcebde84ed0449ea69005bbdfa2e92349c2155272df5f7c5132c1754b4490feccac5890d5d876a2fd59d1878255b003207d65fe2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/445fb90f75bbf159f74e76f33017af1a79bbeb595f460ff7ca4c2c7ab7a20e76854aa059dc717cb592afd2274a693aeca3fe6ab3926430918f00f296405d0506", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d2953e7f3bfb423965e21c481cdf0bddbf5e2310f7746af9e81d16cb3d5b5d62deede06912c31532018ae61c1fbb0388d2e9f23e95b2612216a4b6ba09cfe2ce"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 01:03:34.650410) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 01:03:34.650773) ========= 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.Wmgagls9MT/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.gnj0n6JnkI/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.004980 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 01:03:34.700110) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 01:03:34.700450) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 01:03:34.700793) ========= ========= Total master_lag: 0.20 =========