builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0276 starttime: 1479365582.05 results: success (0) buildid: 20161116203623 builduid: e4955c3fccea48c18b331d3046e18862 revision: 13f49da109ea460665ad27c8497cb1489548450c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:02.050959) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:02.051428) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:02.051712) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:02.079494) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:02.079887) ========= 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.ROIdWbZE9H/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.46fb5GinWZ/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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-16 22:53:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.8M=0.001s 2016-11-16 22:53:02 (17.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151979 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:02.257304) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:02.257627) ========= 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.ROIdWbZE9H/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.46fb5GinWZ/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.024815 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:02.306032) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-16 22:53:02.309399) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 13f49da109ea460665ad27c8497cb1489548450c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 13f49da109ea460665ad27c8497cb1489548450c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ROIdWbZE9H/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.46fb5GinWZ/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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-16 22:53:02,410 truncating revision to first 12 chars 2016-11-16 22:53:02,410 Setting DEBUG logging. 2016-11-16 22:53:02,410 attempt 1/10 2016-11-16 22:53:02,410 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/13f49da109ea?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-16 22:53:03,983 unpacking tar archive at: mozilla-central-13f49da109ea/testing/mozharness/ program finished with exit code 0 elapsedTime=1.867807 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-16 22:53:04.199034) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:04.199378) ========= script_repo_revision: 13f49da109ea460665ad27c8497cb1489548450c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:04.199762) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:04.200037) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:04.220600) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-16 22:53:04.221027) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ROIdWbZE9H/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.46fb5GinWZ/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 22:53:04 INFO - MultiFileLogger online at 20161116 22:53:04 in /builds/slave/test 22:53:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:53:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:53:04 INFO - {'append_to_log': False, 22:53:04 INFO - 'base_work_dir': '/builds/slave/test', 22:53:04 INFO - 'blob_upload_branch': 'Firefox', 22:53:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:53:04 INFO - 'branch': 'Firefox', 22:53:04 INFO - 'buildbot_json_path': 'buildprops.json', 22:53:04 INFO - 'config_files': ('talos/mac_config.py',), 22:53:04 INFO - 'default_actions': ('clobber', 22:53:04 INFO - 'read-buildbot-config', 22:53:04 INFO - 'download-and-extract', 22:53:04 INFO - 'populate-webroot', 22:53:04 INFO - 'create-virtualenv', 22:53:04 INFO - 'install', 22:53:04 INFO - 'run-tests'), 22:53:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:53:04 INFO - 'download_minidump_stackwalk': True, 22:53:04 INFO - 'download_symbols': 'ondemand', 22:53:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:53:04 INFO - 'tooltool.py': '/tools/tooltool.py', 22:53:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:53:04 INFO - '/tools/misc-python/virtualenv.py')}, 22:53:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:53:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:53:04 INFO - 'installer_path': 'installer.exe', 22:53:04 INFO - 'log_level': 'info', 22:53:04 INFO - 'log_name': 'talos', 22:53:04 INFO - 'log_to_console': True, 22:53:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:53:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:53:04 INFO - 'opt_config_files': (), 22:53:04 INFO - 'pip_index': False, 22:53:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:53:04 INFO - 'cmd': ('bash', 22:53:04 INFO - '-c', 22:53:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:53:04 INFO - 'enabled': True, 22:53:04 INFO - 'halt_on_failure': False, 22:53:04 INFO - 'name': 'check_screen_resolution'},), 22:53:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:53:04 INFO - 'cmd': ('bash', 22:53:04 INFO - '-c', 22:53:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:53:04 INFO - 'enabled': True, 22:53:04 INFO - 'halt_on_failure': False, 22:53:04 INFO - 'name': 'check_screen_resolution'},), 22:53:04 INFO - 'run_cmd_checks_enabled': True, 22:53:04 INFO - 'sps_profile': False, 22:53:04 INFO - 'sps_profile_interval': 0, 22:53:04 INFO - 'suite': 'dromaeojs', 22:53:04 INFO - 'system_bits': '32', 22:53:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:53:04 INFO - 'title': 't-yosemite-r7-0276', 22:53:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:53:04 INFO - 'use_talos_json': True, 22:53:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:53:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:53:04 INFO - 'work_dir': 'build'} 22:53:04 INFO - [mozharness: 2016-11-17 06:53:04.429300Z] Running clobber step. 22:53:04 INFO - Running pre-action listener: _resource_record_pre_action 22:53:04 INFO - Running main action method: clobber 22:53:04 INFO - rmtree: /builds/slave/test/build 22:53:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:53:06 INFO - Running post-action listener: _resource_record_post_action 22:53:06 INFO - [mozharness: 2016-11-17 06:53:06.873471Z] Finished clobber step (success) 22:53:06 INFO - [mozharness: 2016-11-17 06:53:06.873594Z] Running read-buildbot-config step. 22:53:06 INFO - Running pre-action listener: _resource_record_pre_action 22:53:06 INFO - Running main action method: read_buildbot_config 22:53:06 INFO - Using buildbot properties: 22:53:06 INFO - { 22:53:06 INFO - "project": "", 22:53:06 INFO - "product": "firefox", 22:53:06 INFO - "script_repo_revision": "production", 22:53:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 22:53:06 INFO - "repository": "", 22:53:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 22:53:06 INFO - "buildid": "20161116203623", 22:53:06 INFO - "pgo_build": "False", 22:53:06 INFO - "basedir": "/builds/slave/test", 22:53:06 INFO - "buildnumber": 23, 22:53:06 INFO - "slavename": "t-yosemite-r7-0276", 22:53:06 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 22:53:06 INFO - "platform": "yosemite_r7", 22:53:06 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:53:06 INFO - "branch": "mozilla-central", 22:53:06 INFO - "repo_path": "mozilla-central", 22:53:06 INFO - "revision": "13f49da109ea460665ad27c8497cb1489548450c", 22:53:06 INFO - "stage_platform": "macosx64", 22:53:06 INFO - "builduid": "e4955c3fccea48c18b331d3046e18862", 22:53:06 INFO - "slavebuilddir": "test" 22:53:06 INFO - } 22:53:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 22:53:06 INFO - Running post-action listener: _resource_record_post_action 22:53:06 INFO - [mozharness: 2016-11-17 06:53:06.877608Z] Finished read-buildbot-config step (success) 22:53:06 INFO - [mozharness: 2016-11-17 06:53:06.877751Z] Running download-and-extract step. 22:53:06 INFO - Running pre-action listener: _resource_record_pre_action 22:53:06 INFO - Running main action method: download_and_extract 22:53:06 INFO - mkdir: /builds/slave/test/build/tests 22:53:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:53:06 INFO - trying https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 22:53:06 INFO - Downloading https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/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 22:53:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/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 22:53:07 INFO - Downloaded 1199 bytes. 22:53:07 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 22:53:07 INFO - Using the following test package requirements: 22:53:07 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 22:53:07 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:07 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 22:53:07 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:07 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 22:53:07 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:07 INFO - u'jsshell-mac.zip'], 22:53:07 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:07 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 22:53:07 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 22:53:07 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:07 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 22:53:07 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:07 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 22:53:07 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:07 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 22:53:07 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:07 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 22:53:07 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:53:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 22:53:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:53:07 INFO - Fetch https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 22:53:08 INFO - Http code: 200 22:53:08 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 22:53:08 INFO - x-amz-cf-id: npUHSyB3SEmXvC0qr2RLkQSS-ddRUQDr18_Ph7Bcc9WeU7cIrhcpjA== 22:53:08 INFO - x-amz-version-id: pWW1DWMkf7BkNxnAYDTOLL7lUrCr06_f 22:53:08 INFO - Expected file size: 16273480 22:53:08 INFO - Obtained file size: 16273480 22:53:15 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 22:53:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 22:53:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:53:15 INFO - Fetch https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 22:53:15 INFO - Http code: 200 22:53:15 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 22:53:15 INFO - x-amz-cf-id: VokTfA5vqd3aFvnvb5KG5CMPH9WBFz8i58LeE6XfeHVK-y0TdyFrgQ== 22:53:15 INFO - x-amz-version-id: pWW1DWMkf7BkNxnAYDTOLL7lUrCr06_f 22:53:16 INFO - Expected file size: 16273480 22:53:16 INFO - Obtained file size: 16273480 22:53:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 22:53:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:53:22 INFO - Fetch https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 22:53:23 INFO - Http code: 200 22:53:23 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 22:53:23 INFO - x-amz-cf-id: k9kjvA1ZOh9HUIQyDGinJ2IBm6eiHPxuGLj5H1kxBx2mWB6_fBRnvA== 22:53:23 INFO - x-amz-version-id: .xNxLe0wM63LnjA0mOlcw5iiY7hg4OUD 22:53:23 INFO - Expected file size: 14409389 22:53:23 INFO - Obtained file size: 14409389 22:53:24 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')]} 22:53:24 INFO - trying https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:53:24 INFO - Downloading https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 22:53:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/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 22:53:27 INFO - Downloaded 110986238 bytes. 22:53:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:53:27 INFO - mkdir: /builds/slave/test/properties 22:53:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:53:27 INFO - Writing to file /builds/slave/test/properties/build_url 22:53:27 INFO - Contents: 22:53:27 INFO - build_url:https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:53:28 INFO - Running post-action listener: _resource_record_post_action 22:53:28 INFO - Running post-action listener: set_extra_try_arguments 22:53:28 INFO - [mozharness: 2016-11-17 06:53:28.219512Z] Finished download-and-extract step (success) 22:53:28 INFO - [mozharness: 2016-11-17 06:53:28.219642Z] Running populate-webroot step. 22:53:28 INFO - Running pre-action listener: _resource_record_pre_action 22:53:28 INFO - Running main action method: populate_webroot 22:53:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:53:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:53:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:53:28 INFO - '--tppagecycles', 22:53:28 INFO - '7'], 22:53:28 INFO - 'tests': ['tsvgm']}}, 22:53:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:53:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:53:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:53:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:53:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:53:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:53:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:53:28 INFO - 'talos_options': ['--disable-e10s'], 22:53:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:53:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:53:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:53:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:53:28 INFO - 'talos_options': ['--disable-e10s'], 22:53:28 INFO - 'tests': ['damp', 'tps']}, 22:53:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:53:28 INFO - 'tests': ['damp', 'tps']}, 22:53:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:53:28 INFO - 'tests': ['dromaeo_dom']}, 22:53:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:53:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:53:28 INFO - 'tests': ['basic_compositor_video']}, 22:53:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:53:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:53:28 INFO - 'tests': ['a11yr', 22:53:28 INFO - 'ts_paint', 22:53:28 INFO - 'tpaint', 22:53:28 INFO - 'sessionrestore', 22:53:28 INFO - 'sessionrestore_no_auto_restore', 22:53:28 INFO - 'tabpaint']}, 22:53:28 INFO - 'other-e10s': {'tests': ['a11yr', 22:53:28 INFO - 'ts_paint', 22:53:28 INFO - 'tpaint', 22:53:28 INFO - 'sessionrestore', 22:53:28 INFO - 'sessionrestore_no_auto_restore', 22:53:28 INFO - 'tabpaint']}, 22:53:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:53:28 INFO - 'ts_paint', 22:53:28 INFO - 'tpaint', 22:53:28 INFO - 'sessionrestore', 22:53:28 INFO - 'sessionrestore_no_auto_restore', 22:53:28 INFO - 'tabpaint']}, 22:53:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:53:28 INFO - 'ts_paint', 22:53:28 INFO - 'tpaint', 22:53:28 INFO - 'sessionrestore', 22:53:28 INFO - 'sessionrestore_no_auto_restore', 22:53:28 INFO - 'tabpaint']}, 22:53:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:53:28 INFO - 'tests': ['a11yr', 22:53:28 INFO - 'ts_paint', 22:53:28 INFO - 'tpaint', 22:53:28 INFO - 'sessionrestore', 22:53:28 INFO - 'sessionrestore_no_auto_restore', 22:53:28 INFO - 'tabpaint']}, 22:53:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:53:28 INFO - 'tests': ['a11yr', 22:53:28 INFO - 'ts_paint', 22:53:28 INFO - 'tpaint', 22:53:28 INFO - 'sessionrestore', 22:53:28 INFO - 'sessionrestore_no_auto_restore', 22:53:28 INFO - 'tabpaint']}, 22:53:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:53:28 INFO - 'tests': ['tsvgx', 22:53:28 INFO - 'tsvgr_opacity', 22:53:28 INFO - 'tart', 22:53:28 INFO - 'tscrollx', 22:53:28 INFO - 'cart']}, 22:53:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:53:28 INFO - 'tsvgr_opacity', 22:53:28 INFO - 'tart', 22:53:28 INFO - 'tscrollx', 22:53:28 INFO - 'cart']}, 22:53:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:53:28 INFO - 'talos_options': ['--disable-e10s'], 22:53:28 INFO - 'tests': ['tp5o']}, 22:53:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:53:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:53:28 INFO - 'talos_options': ['--disable-e10s', 22:53:28 INFO - '--xperf_path', 22:53:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:53:28 INFO - 'tests': ['tp5n']}, 22:53:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:53:28 INFO - 'talos_options': ['--xperf_path', 22:53:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:53:28 INFO - 'tests': ['tp5n']}}, 22:53:28 INFO - 'talos.zip': {'path': '', 22:53:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:53:28 INFO - Running post-action listener: _resource_record_post_action 22:53:28 INFO - [mozharness: 2016-11-17 06:53:28.237813Z] Finished populate-webroot step (success) 22:53:28 INFO - [mozharness: 2016-11-17 06:53:28.237931Z] Running create-virtualenv step. 22:53:28 INFO - Running pre-action listener: _resource_record_pre_action 22:53:28 INFO - Running main action method: create_virtualenv 22:53:28 INFO - Creating virtualenv /builds/slave/test/build/venv 22:53:28 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 22:53:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:53:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:53:28 INFO - Using real prefix '/tools/python27' 22:53:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:53:29 INFO - Installing distribute.............................................................................................................................................................................................done. 22:53:31 INFO - Installing pip.................done. 22:53:31 INFO - Return code: 0 22:53:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:53:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:53:32 INFO - Reading from file tmpfile_stdout 22:53:32 INFO - Output received: 22:53:32 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) 22:53:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:53:32 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')]} 22:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:32 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')]} 22:53:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:32 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 0x106bd33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b49570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d1571f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e9d30>, '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 0x106850db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069e1b20>, '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.ROIdWbZE9H/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.46fb5GinWZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 22:53:32 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 22:53:32 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 22:53:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROIdWbZE9H/Render', 22:53:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:32 INFO - 'HOME': '/Users/cltbld', 22:53:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:32 INFO - 'LOGNAME': 'cltbld', 22:53:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:32 INFO - 'MOZ_NO_REMOTE': '1', 22:53:32 INFO - 'NO_EM_RESTART': '1', 22:53:32 INFO - 'PAGER': '/bin/cat', 22:53:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:32 INFO - 'PWD': '/builds/slave/test', 22:53:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:32 INFO - 'SHELL': '/bin/bash', 22:53:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.46fb5GinWZ/Listeners', 22:53:32 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 22:53:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:32 INFO - 'USER': 'cltbld', 22:53:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:32 INFO - 'XPC_FLAGS': '0x0', 22:53:32 INFO - 'XPC_SERVICE_NAME': '0', 22:53:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:32 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 22:53:32 INFO - Cleaning up... 22:53:32 INFO - Return code: 0 22:53:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:53:32 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')]} 22:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:32 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')]} 22:53:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:32 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 0x106bd33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b49570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d1571f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e9d30>, '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 0x106850db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069e1b20>, '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.ROIdWbZE9H/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.46fb5GinWZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 22:53:32 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 22:53:32 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 22:53:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROIdWbZE9H/Render', 22:53:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:32 INFO - 'HOME': '/Users/cltbld', 22:53:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:32 INFO - 'LOGNAME': 'cltbld', 22:53:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:32 INFO - 'MOZ_NO_REMOTE': '1', 22:53:32 INFO - 'NO_EM_RESTART': '1', 22:53:32 INFO - 'PAGER': '/bin/cat', 22:53:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:32 INFO - 'PWD': '/builds/slave/test', 22:53:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:32 INFO - 'SHELL': '/bin/bash', 22:53:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.46fb5GinWZ/Listeners', 22:53:32 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 22:53:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:32 INFO - 'USER': 'cltbld', 22:53:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:32 INFO - 'XPC_FLAGS': '0x0', 22:53:32 INFO - 'XPC_SERVICE_NAME': '0', 22:53:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:32 INFO - Downloading/unpacking psutil>=3.1.1 22:53:32 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 22:53:32 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 22:53:32 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 22:53:32 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 22:53:32 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 22:53:32 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 22:53:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:53:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:53:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:53:36 INFO - Installing collected packages: psutil 22:53:36 INFO - Running setup.py install for psutil 22:53:36 INFO - building 'psutil._psutil_osx' extension 22:53:36 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 22:53:36 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 22:53:36 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 22:53:36 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 22:53:36 INFO - building 'psutil._psutil_posix' extension 22:53:36 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 22:53:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:53:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:53:37 INFO - ^ 22:53:37 INFO - 1 warning generated. 22:53:37 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 22:53:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:53:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:53:37 INFO - Successfully installed psutil 22:53:37 INFO - Cleaning up... 22:53:37 INFO - Return code: 0 22:53:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:53:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:53:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106bd33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b49570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d1571f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e9d30>, '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 0x106850db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069e1b20>, '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.ROIdWbZE9H/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.46fb5GinWZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 22:53:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:53:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:53:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROIdWbZE9H/Render', 22:53:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:37 INFO - 'HOME': '/Users/cltbld', 22:53:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:37 INFO - 'LOGNAME': 'cltbld', 22:53:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:37 INFO - 'MOZ_NO_REMOTE': '1', 22:53:37 INFO - 'NO_EM_RESTART': '1', 22:53:37 INFO - 'PAGER': '/bin/cat', 22:53:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:37 INFO - 'PWD': '/builds/slave/test', 22:53:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:37 INFO - 'SHELL': '/bin/bash', 22:53:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.46fb5GinWZ/Listeners', 22:53:37 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 22:53:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:37 INFO - 'USER': 'cltbld', 22:53:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:37 INFO - 'XPC_FLAGS': '0x0', 22:53:37 INFO - 'XPC_SERVICE_NAME': '0', 22:53:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:53:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:53:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:53:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:53:40 INFO - Installing collected packages: mozsystemmonitor 22:53:40 INFO - Running setup.py install for mozsystemmonitor 22:53:40 INFO - Successfully installed mozsystemmonitor 22:53:40 INFO - Cleaning up... 22:53:40 INFO - Return code: 0 22:53:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:53:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:53:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106bd33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b49570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d1571f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e9d30>, '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 0x106850db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069e1b20>, '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.ROIdWbZE9H/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.46fb5GinWZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 22:53:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:53:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:53:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROIdWbZE9H/Render', 22:53:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:40 INFO - 'HOME': '/Users/cltbld', 22:53:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:40 INFO - 'LOGNAME': 'cltbld', 22:53:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:40 INFO - 'MOZ_NO_REMOTE': '1', 22:53:40 INFO - 'NO_EM_RESTART': '1', 22:53:40 INFO - 'PAGER': '/bin/cat', 22:53:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:40 INFO - 'PWD': '/builds/slave/test', 22:53:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:40 INFO - 'SHELL': '/bin/bash', 22:53:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.46fb5GinWZ/Listeners', 22:53:40 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 22:53:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:40 INFO - 'USER': 'cltbld', 22:53:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:40 INFO - 'XPC_FLAGS': '0x0', 22:53:40 INFO - 'XPC_SERVICE_NAME': '0', 22:53:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:40 INFO - Downloading/unpacking jsonschema==2.5.1 22:53:40 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 22:53:40 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 22:53:40 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 22:53:40 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 22:53:40 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 22:53:40 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 22:53:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:53:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:43 INFO - Downloading functools32-3.2.3-2.tar.gz 22:53:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:53:43 INFO - warning: no files found matching '*.txt' 22:53:43 INFO - no previously-included directories found matching 'build' 22:53:43 INFO - no previously-included directories found matching 'dist' 22:53:43 INFO - no previously-included directories found matching '.git*' 22:53:43 INFO - Installing collected packages: jsonschema, functools32 22:53:43 INFO - Running setup.py install for functools32 22:53:43 INFO - warning: no files found matching '*.txt' 22:53:43 INFO - no previously-included directories found matching 'build' 22:53:43 INFO - no previously-included directories found matching 'dist' 22:53:43 INFO - no previously-included directories found matching '.git*' 22:53:43 INFO - Successfully installed jsonschema functools32 22:53:43 INFO - Cleaning up... 22:53:44 INFO - Return code: 0 22:53:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:53:44 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')]} 22:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:44 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')]} 22:53:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:44 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 0x106bd33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b49570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d1571f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e9d30>, '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 0x106850db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069e1b20>, '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.ROIdWbZE9H/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.46fb5GinWZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 22:53:44 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 22:53:44 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 22:53:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROIdWbZE9H/Render', 22:53:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:44 INFO - 'HOME': '/Users/cltbld', 22:53:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:44 INFO - 'LOGNAME': 'cltbld', 22:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:44 INFO - 'MOZ_NO_REMOTE': '1', 22:53:44 INFO - 'NO_EM_RESTART': '1', 22:53:44 INFO - 'PAGER': '/bin/cat', 22:53:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:44 INFO - 'PWD': '/builds/slave/test', 22:53:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:44 INFO - 'SHELL': '/bin/bash', 22:53:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.46fb5GinWZ/Listeners', 22:53:44 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 22:53:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:44 INFO - 'USER': 'cltbld', 22:53:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:44 INFO - 'XPC_FLAGS': '0x0', 22:53:44 INFO - 'XPC_SERVICE_NAME': '0', 22:53:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:53:44 INFO - Cleaning up... 22:53:44 INFO - Return code: 0 22:53:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:53:44 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')]} 22:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:44 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')]} 22:53:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:44 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 0x106bd33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b49570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d1571f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e9d30>, '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 0x106850db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069e1b20>, '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.ROIdWbZE9H/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.46fb5GinWZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 22:53:44 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 22:53:44 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 22:53:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROIdWbZE9H/Render', 22:53:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:44 INFO - 'HOME': '/Users/cltbld', 22:53:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:44 INFO - 'LOGNAME': 'cltbld', 22:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:44 INFO - 'MOZ_NO_REMOTE': '1', 22:53:44 INFO - 'NO_EM_RESTART': '1', 22:53:44 INFO - 'PAGER': '/bin/cat', 22:53:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:44 INFO - 'PWD': '/builds/slave/test', 22:53:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:44 INFO - 'SHELL': '/bin/bash', 22:53:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.46fb5GinWZ/Listeners', 22:53:44 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 22:53:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:44 INFO - 'USER': 'cltbld', 22:53:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:44 INFO - 'XPC_FLAGS': '0x0', 22:53:44 INFO - 'XPC_SERVICE_NAME': '0', 22:53:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:44 INFO - Downloading/unpacking blobuploader==1.2.4 22:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:46 INFO - Downloading blobuploader-1.2.4.tar.gz 22:53:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:53:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:53:47 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 22:53:47 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 22:53:47 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 22:53:47 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 22:53:47 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 22:53:47 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 22:53:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:53:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:53:47 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 22:53:47 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 22:53:47 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 22:53:47 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 22:53:47 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 22:53:47 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 22:53:48 INFO - Downloading docopt-0.6.1.tar.gz 22:53:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:53:48 INFO - Installing collected packages: blobuploader, requests, docopt 22:53:48 INFO - Running setup.py install for blobuploader 22:53:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:53:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:53:48 INFO - Running setup.py install for requests 22:53:49 INFO - Running setup.py install for docopt 22:53:49 INFO - Successfully installed blobuploader requests docopt 22:53:49 INFO - Cleaning up... 22:53:49 INFO - Return code: 0 22:53:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:53:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:49 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 0x106bd33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b49570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d1571f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e9d30>, '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 0x106850db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069e1b20>, '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.ROIdWbZE9H/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.46fb5GinWZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 22:53:49 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 22:53:49 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 22:53:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROIdWbZE9H/Render', 22:53:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:49 INFO - 'HOME': '/Users/cltbld', 22:53:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:49 INFO - 'LOGNAME': 'cltbld', 22:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:49 INFO - 'MOZ_NO_REMOTE': '1', 22:53:49 INFO - 'NO_EM_RESTART': '1', 22:53:49 INFO - 'PAGER': '/bin/cat', 22:53:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:49 INFO - 'PWD': '/builds/slave/test', 22:53:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:49 INFO - 'SHELL': '/bin/bash', 22:53:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.46fb5GinWZ/Listeners', 22:53:49 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 22:53:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:49 INFO - 'USER': 'cltbld', 22:53:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:49 INFO - 'XPC_FLAGS': '0x0', 22:53:49 INFO - 'XPC_SERVICE_NAME': '0', 22:53:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:53:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-UlWK9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:53:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-9rwzXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:53:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-WScWt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:53:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-CzoVgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:53:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-j3CgZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:53:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-fXXVZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:53:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-uwhraQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:53:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-494Ehi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:53:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ESyDZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:53:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Z_O5yi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:53:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-_8XbdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:53:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-EySipN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:53:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Vh2DnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:53:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-rq5_k_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-kUU0zK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:53:51 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-t7bw__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:53:51 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-0g5weh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:53:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:53:51 INFO - Running setup.py install for manifestparser 22:53:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:53:51 INFO - Running setup.py install for mozcrash 22:53:51 INFO - Running setup.py install for mozdebug 22:53:51 INFO - Running setup.py install for mozdevice 22:53:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:53:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:53:51 INFO - Running setup.py install for mozfile 22:53:51 INFO - Running setup.py install for mozhttpd 22:53:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:53:52 INFO - Running setup.py install for mozinfo 22:53:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:53:52 INFO - Running setup.py install for mozInstall 22:53:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:53:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:53:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:53:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:53:52 INFO - Running setup.py install for mozleak 22:53:52 INFO - Running setup.py install for mozlog 22:53:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:53:52 INFO - Running setup.py install for moznetwork 22:53:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:53:52 INFO - Running setup.py install for mozprocess 22:53:53 INFO - Running setup.py install for mozprofile 22:53:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:53:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:53:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:53:53 INFO - Running setup.py install for mozrunner 22:53:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:53:53 INFO - Running setup.py install for mozscreenshot 22:53:53 INFO - Running setup.py install for moztest 22:53:53 INFO - Running setup.py install for mozversion 22:53:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:53:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:53:53 INFO - Cleaning up... 22:53:53 INFO - Return code: 0 22:53:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:53:53 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')]} 22:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:53 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')]} 22:53:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:53 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 0x106bd33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b49570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d1571f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e9d30>, '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 0x106850db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069e1b20>, '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.ROIdWbZE9H/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.46fb5GinWZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 22:53:53 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 22:53:53 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 22:53:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROIdWbZE9H/Render', 22:53:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:53 INFO - 'HOME': '/Users/cltbld', 22:53:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:53 INFO - 'LOGNAME': 'cltbld', 22:53:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:53 INFO - 'MOZ_NO_REMOTE': '1', 22:53:53 INFO - 'NO_EM_RESTART': '1', 22:53:53 INFO - 'PAGER': '/bin/cat', 22:53:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:53 INFO - 'PWD': '/builds/slave/test', 22:53:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:53 INFO - 'SHELL': '/bin/bash', 22:53:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.46fb5GinWZ/Listeners', 22:53:53 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 22:53:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:53 INFO - 'USER': 'cltbld', 22:53:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:53 INFO - 'XPC_FLAGS': '0x0', 22:53:53 INFO - 'XPC_SERVICE_NAME': '0', 22:53:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:53:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-C6ufp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:53:54 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)) 22:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:53:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-bLIrId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:53:54 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)) 22:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:53:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-rAC7G2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:53:54 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)) 22:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:53:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-sKqBWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:53:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-AMv4hP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:53:54 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)) 22:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:53:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-qPd81m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:53:54 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)) 22:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:53:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-K5pCyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:53:54 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)) 22:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:53:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Psd2Ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:53:54 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)) 22:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:53:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-75_6W4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:53:55 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)) 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:53:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-vmDhF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:53:55 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)) 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:53:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-t6gjLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:53:55 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)) 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:53:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-btMFSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:53:55 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)) 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:53:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ynjy3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:53:55 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)) 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:53:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-3E4y_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:53:55 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)) 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-AsIxdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:55 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)) 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:53:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-XuAc76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:53:55 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)) 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:53:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-xvYOdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:53:55 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)) 22:53:55 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)) 22:53:55 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)) 22:53:55 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)) 22:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:53:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:53:55 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 22:53:55 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 22:53:55 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 22:53:55 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 22:53:55 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 22:53:55 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 22:53:58 INFO - Downloading blessings-1.6.tar.gz 22:53:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:53:58 INFO - Installing collected packages: blessings 22:53:58 INFO - Running setup.py install for blessings 22:53:58 INFO - Successfully installed blessings 22:53:58 INFO - Cleaning up... 22:53:58 INFO - Return code: 0 22:53:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:53:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:53:59 INFO - Reading from file tmpfile_stdout 22:53:59 INFO - Current package versions: 22:53:59 INFO - blessings == 1.6 22:53:59 INFO - blobuploader == 1.2.4 22:53:59 INFO - docopt == 0.6.1 22:53:59 INFO - functools32 == 3.2.3-2 22:53:59 INFO - jsonschema == 2.5.1 22:53:59 INFO - manifestparser == 1.1 22:53:59 INFO - mozInstall == 1.12 22:53:59 INFO - mozcrash == 1.0 22:53:59 INFO - mozdebug == 0.1 22:53:59 INFO - mozdevice == 0.48 22:53:59 INFO - mozfile == 1.2 22:53:59 INFO - mozhttpd == 0.7 22:53:59 INFO - mozinfo == 0.9 22:53:59 INFO - mozleak == 0.1 22:53:59 INFO - mozlog == 3.4 22:53:59 INFO - moznetwork == 0.27 22:53:59 INFO - mozprocess == 0.23 22:53:59 INFO - mozprofile == 0.28 22:53:59 INFO - mozrunner == 6.13 22:53:59 INFO - mozscreenshot == 0.1 22:53:59 INFO - mozsystemmonitor == 0.3 22:53:59 INFO - moztest == 0.7 22:53:59 INFO - mozversion == 1.4 22:53:59 INFO - psutil == 3.1.1 22:53:59 INFO - requests == 1.2.3 22:53:59 INFO - wsgiref == 0.1.2 22:53:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:53:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:53:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:59 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 0x106bd33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b49570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d1571f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e9d30>, '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 0x106850db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069e1b20>, '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.ROIdWbZE9H/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.46fb5GinWZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 22:53:59 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 22:53:59 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 22:53:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROIdWbZE9H/Render', 22:53:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:59 INFO - 'HOME': '/Users/cltbld', 22:53:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:59 INFO - 'LOGNAME': 'cltbld', 22:53:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:59 INFO - 'MOZ_NO_REMOTE': '1', 22:53:59 INFO - 'NO_EM_RESTART': '1', 22:53:59 INFO - 'PAGER': '/bin/cat', 22:53:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:59 INFO - 'PWD': '/builds/slave/test', 22:53:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:59 INFO - 'SHELL': '/bin/bash', 22:53:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.46fb5GinWZ/Listeners', 22:53:59 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 22:53:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:59 INFO - 'USER': 'cltbld', 22:53:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:59 INFO - 'XPC_FLAGS': '0x0', 22:53:59 INFO - 'XPC_SERVICE_NAME': '0', 22:53:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:00 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)) 22:54:00 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)) 22:54:00 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)) 22:54:00 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)) 22:54:00 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)) 22:54:00 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)) 22:54:00 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)) 22:54:00 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)) 22:54:00 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)) 22:54:00 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)) 22:54:00 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)) 22:54:00 INFO - Cleaning up... 22:54:00 INFO - Return code: 0 22:54:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:54:00 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')]} 22:54:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:00 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')]} 22:54:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:00 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 0x106bd33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b49570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d1571f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e9d30>, '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 0x106850db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069e1b20>, '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.ROIdWbZE9H/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.46fb5GinWZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 22:54:00 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 22:54:00 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 22:54:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROIdWbZE9H/Render', 22:54:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:00 INFO - 'HOME': '/Users/cltbld', 22:54:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:00 INFO - 'LOGNAME': 'cltbld', 22:54:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:00 INFO - 'MOZ_NO_REMOTE': '1', 22:54:00 INFO - 'NO_EM_RESTART': '1', 22:54:00 INFO - 'PAGER': '/bin/cat', 22:54:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:00 INFO - 'PWD': '/builds/slave/test', 22:54:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:00 INFO - 'SHELL': '/bin/bash', 22:54:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.46fb5GinWZ/Listeners', 22:54:00 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 22:54:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:00 INFO - 'USER': 'cltbld', 22:54:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:00 INFO - 'XPC_FLAGS': '0x0', 22:54:00 INFO - 'XPC_SERVICE_NAME': '0', 22:54:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:54:00 INFO - Cleaning up... 22:54:00 INFO - Return code: 0 22:54:00 INFO - Running post-action listener: _resource_record_post_action 22:54:00 INFO - Running post-action listener: _start_resource_monitoring 22:54:00 INFO - Starting resource monitoring. 22:54:00 INFO - [mozharness: 2016-11-17 06:54:00.779734Z] Finished create-virtualenv step (success) 22:54:00 INFO - [mozharness: 2016-11-17 06:54:00.780758Z] Running install step. 22:54:00 INFO - Running pre-action listener: _resource_record_pre_action 22:54:00 INFO - Running main action method: install 22:54:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:54:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:54:01 INFO - Reading from file tmpfile_stdout 22:54:01 INFO - Detecting whether we're running mozinstall >=1.0... 22:54:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:54:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:54:01 INFO - Reading from file tmpfile_stdout 22:54:01 INFO - Output received: 22:54:01 INFO - Usage: mozinstall [options] installer 22:54:01 INFO - Options: 22:54:01 INFO - -h, --help show this help message and exit 22:54:01 INFO - -d DEST, --destination=DEST 22:54:01 INFO - Directory to install application into. [default: 22:54:01 INFO - "/builds/slave/test"] 22:54:01 INFO - --app=APP Application being installed. [default: firefox] 22:54:01 INFO - mkdir: /builds/slave/test/build/application 22:54:01 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'] 22:54:01 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 22:54:35 INFO - Reading from file tmpfile_stdout 22:54:35 INFO - Output received: 22:54:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:54:35 INFO - Running post-action listener: _resource_record_post_action 22:54:35 INFO - [mozharness: 2016-11-17 06:54:35.409165Z] Finished install step (success) 22:54:35 INFO - [mozharness: 2016-11-17 06:54:35.409312Z] Running run-tests step. 22:54:35 INFO - Running pre-action listener: _resource_record_pre_action 22:54:35 INFO - Running main action method: run_tests 22:54:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:54:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:54:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:54:35 INFO - 2016-11-16 22:54:35.423 screenresolution[1622:7277] starting screenresolution argv=screenresolution get 22:54:35 INFO - 2016-11-16 22:54:35.426 screenresolution[1622:7277] Display 0: 1600x1200x32@60 22:54:35 INFO - 2016-11-16 22:54:35.432 screenresolution[1623:7279] starting screenresolution argv=screenresolution list 22:54:35 INFO - Available Modes on Display 0 22:54:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:54:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:54:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:54:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:54:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:54:35 INFO - Intel Iris: 22:54:35 INFO - Chipset Model: Intel Iris 22:54:35 INFO - Type: GPU 22:54:35 INFO - Bus: Built-In 22:54:35 INFO - VRAM (Dynamic, Max): 1536 MB 22:54:35 INFO - Vendor: Intel (0x8086) 22:54:35 INFO - Device ID: 0x0a2e 22:54:35 INFO - Revision ID: 0x0009 22:54:35 INFO - Displays: 22:54:35 INFO - Display: 22:54:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:54:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:54:35 INFO - Display Serial Number: 1600x1200 60 22:54:35 INFO - Main Display: Yes 22:54:35 INFO - Mirror: Off 22:54:35 INFO - Online: Yes 22:54:35 INFO - Rotation: Supported 22:54:35 INFO - Return code: 0 22:54:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:54:35 INFO - Python 2.7.3 22:54:35 INFO - Return code: 0 22:54:35 INFO - grabbing minidump binary from tooltool 22:54:35 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')]} 22:54:35 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 0x1069e9d30>, '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 0x106850db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069e1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:54:35 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 22:54:35 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 22:54:35 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 22:54:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:54:35 INFO - Return code: 0 22:54:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:54:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:54:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:54:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:54:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:54:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--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 22:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0276 --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 22:54:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROIdWbZE9H/Render', 22:54:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:35 INFO - 'HOME': '/Users/cltbld', 22:54:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:35 INFO - 'LOGNAME': 'cltbld', 22:54:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:54:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:54:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:35 INFO - 'MOZ_NO_REMOTE': '1', 22:54:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:54:35 INFO - 'NO_EM_RESTART': '1', 22:54:35 INFO - 'PAGER': '/bin/cat', 22:54:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:35 INFO - 'PWD': '/builds/slave/test', 22:54:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:54:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:35 INFO - 'SHELL': '/bin/bash', 22:54:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.46fb5GinWZ/Listeners', 22:54:35 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 22:54:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:35 INFO - 'USER': 'cltbld', 22:54:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:35 INFO - 'XPC_FLAGS': '0x0', 22:54:35 INFO - 'XPC_SERVICE_NAME': '0', 22:54:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--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 22:54:36 INFO - mozversion application_buildid: 20161116203623 22:54:36 INFO - mozversion application_changeset: 13f49da109ea460665ad27c8497cb1489548450c 22:54:36 INFO - mozversion application_display_name: Nightly 22:54:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:54:36 INFO - mozversion application_name: Firefox 22:54:36 INFO - mozversion application_remotingname: firefox 22:54:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:54:36 INFO - mozversion application_vendor: Mozilla 22:54:36 INFO - mozversion application_version: 53.0a1 22:54:36 INFO - mozversion platform_buildid: 20161116203623 22:54:36 INFO - mozversion platform_changeset: 13f49da109ea460665ad27c8497cb1489548450c 22:54:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:54:36 INFO - mozversion platform_version: 53.0a1 22:54:36 INFO - using testdate: 1479365676 22:54:36 INFO - actual date: 1479365676 22:54:36 INFO - starting webserver on 'localhost:49244' 22:54:36 INFO - SUITE-START | Running 2 tests 22:54:36 INFO - TEST-START | dromaeo_css 22:54:36 INFO - operating with platform_type : mac_ 22:54:36 INFO - Initialising browser for dromaeo_css test... 22:54:36 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmp6kGDQ6/profile http://localhost:49244/getInfo.html) 22:54:38 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 22:54:38 INFO - PROCESS | 1633 | colorDepth:24 22:54:38 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 22:54:38 INFO - PROCESS | 1633 | __metrics 22:54:38 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" 22:54:38 INFO - PROCESS | 1633 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:54:39 INFO - TEST-INFO | 1633: exit 0 22:54:39 INFO - Browser initialized. 22:54:39 INFO - Running cycle 1/1 for dromaeo_css test... 22:54:39 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmp6kGDQ6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:54:51 INFO - PROCESS | 1635 | RSS: Main: 237973504 22:54:51 INFO - PROCESS | 1635 | 22:55:42 INFO - PROCESS | 1635 | 1479365742565 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] 22:57:55 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 22:57:57 INFO - PROCESS | 1635 | RSS: Main: 359501824 22:57:57 INFO - PROCESS | 1635 | 23:01:00 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 23:01:01 INFO - PROCESS | 1635 | RSS: Main: 369123328 23:01:01 INFO - PROCESS | 1635 | 23:04:03 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 23:04:04 INFO - PROCESS | 1635 | RSS: Main: 333119488 23:04:04 INFO - PROCESS | 1635 | 23:07:06 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 23:07:07 INFO - PROCESS | 1635 | RSS: Main: 344342528 23:07:07 INFO - PROCESS | 1635 | 23:10:10 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 23:10:11 INFO - PROCESS | 1635 | RSS: Main: 328212480 23:10:11 INFO - PROCESS | 1635 | 23:13:14 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 23:13:14 INFO - PROCESS | 1635 | RSS: Main: 375930880 23:13:14 INFO - PROCESS | 1635 | 23:13:14 INFO - PROCESS | 1635 | __start_tp_report 23:13:14 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 23:13:14 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 23:13:14 INFO - PROCESS | 1635 | |i|pagename|runs| 23:13:14 INFO - PROCESS | 1635 | |0;dojo.html;2666.746659067388;3127.311991361901;3314.3537010283;3353.748468864834;3380.7971521708714;5549.500544950964;5667.744951477183;5669.659820410768;5739.311282646083;5749.252597162374;5591.1054231323;5670.177824215486;5670.744816483258;5681.7727290908315;5688.516476099526;5116.360454943132;5151.974240128794;5163.483651634825;5191.480851914797;5192.065428222918;5274.683518988853;5277.261183434322;5287.206918962148;5288.841334759944;5291.153415453528;5194.818181363653;5223.921641175385;5242.449542797989;5265.763040663161;5272;5258.342707161605;5284.021108231883;5284.9958507883375;5286;5294.894102117936;5036.269740887551;5142;5206.5314121729225;5215.24378965048;5238.607104467142;4794.400699912511;5504.311961004874;5573.721313934287;5599.160125981095;5650.971745141289;6185.35053819347;6190.071489276599;6206.131141640174;6223.25320961483;6250.749970001195;6233.376662333776;6264.9976003839165;6298.905516417258;6304.684765761694;6315.431611155018;5657.830265092054;5706.9714651426475;5709.400512946164;5726.255586773693;5795.768169273266;6254.624722516573;6263.96868015657;6268.749250029948;6278.121063051177;6283.497320214372;6097.969510152421;6100.969495152584;6101.694915254219;6107.541934354941;6113.938860611426;6076.696165191723;6085.17850090241;6107.037103281039;6143.0342290467515;6245.843853903598;6131.69422353494;6173.9073913891325;6175.84560385994;6179.721912513947;6186.1648677427975;6152.077188421773;6216.875662486725;6217.067439883963;6219.346968568235;6246.156768836149;4836.322914791932;4861.562459378602;4916.237983112624;4935.457099719028;4948.084604348207;4319.308910574356;4442.644588432918;4463.754493502888;4473.7986790594505;4479.014616784302;5677.914831277534;5694.772209111672;5696.658200507983;5700.37295897451;5715.914261286084;6144.910730351612;6192.411720886598;6208.2239720034995;6209;6211.0372892201785;5681.062624666966;5709.857253568694;5741;5777.566682498829;5782.190493330937;5365.570754339644;5390.407055223922;5396.271503346998;5400.486953739467;5429.728513574306;6261.843453913689;6264.123022776906;6265.028920517327;6279.843003924847;6285.685715714288;6135.969320153371;6150.477209437248;6163.3836616338;6188.288346840171;6256;5398.3791863934575;6270.9372906270355;6284.842878928063;6289.779857704958;6294.968525157345;6162.198914141133;6177.351378105234;6182.814515564361;6190.380961903774;6197.4732147767945;6107.765388666889;6123.2652081750475;6154.969225153845;6294.402031806971;6299.653519056313;5968.582199246185;6038.456538911519;6060.272767267956;6068.211132552917;6092.299385570539;6058.485028772604;6069.544784141295;6071.969640151771;6083.422074902877;6087.512998960161;5040.193569028937;5678;5790.652560846194;5875.94124058771;5900.940990590211;6022.1870047543025;6054.212952316171;6086.208792857077;6089.5737298388685;6093.268807743099;5933.940660593512;5936.495397891055;5940.702964851826;5954.642721436554;5984.042553191469 23:13:14 INFO - PROCESS | 1635 | |1;ext.html;477.5749582871243;477.6775676418417;492.86446227287513;504.8611631801255;516.8010316028328;31701.36597268053;32972.835135824345;33030.50454243185;33049.834750826245;34541.30917381651;27587.86206068972;33145.834270828615;33193.502097468496;33233;33233.501497477555;6202.596831205957;7204.531705439156;7394.075740532434;7397.889031664529;7401.0748656417945;6257.499400048008;7346.632668366587;7570.16728159902;7581.317681408672;7657.425693073014;7568.886466703004;7613.276738709814;7617.238276172367;7628.275313845176;7646.694132234732;6041.184440100574;6238.844028899269;7524.134724506697;7564.092308922965;7568.659410326521;7364.668589913466;7623.923760762377;7624.809379765495;7694.3069925525615;7708.383329333696;31530;31844.681553184633;31898.84050579732;31909;31911;3212.6305474870455;3229.83850807461;3286.277019055804;3403.2172600301824;3641.3809652359164;21236.893815530824;21555.784442155535;21992.45018874525;22023.22918697838;22152.88923555388;2744.40995186036;2792.7765778737653;2799.2721892308145;2800.425912687894;2811.817231879921;477306;477609;482783;483330.5833470845;483882.0312070348;58961;59475;59561.51096222629;59579;59617.70191149016;33507;34328.50743880967;34641.78753743607;35594.28811423757;35746.8212658935;14655.120692758468;14735;14747.262636868329;14754.557363279118;14785;10946.124310055176;11109.44452777358;11130.443477826077;11917;12006.63980080599;18363.54091147732;18677.813221867607;18822.71765923512;19105.80894191068;19152.90423547901;6212.658303793244;7612.3633418814115;7634.312911837961;7657.425693073042;7663.31030207284;879.7140929198036;948.170350942925;965.9130678238993;966.6665000574791;995.2386424385351;28039.719602804118;29109.41781164365;29374;29503.26241843971;29745;999.7652898670136;1030.2221822523945;1035.7307100153864;1044.5612842606124;1099.1536516881956;14625.488107916171;15094.716949059453;15255.165546427683;15293;15488.457903973307;601024;606732;607478.5138965953;610717;611146;51294.74352628213;51884.740576297634;52058.95882082337;52099.218511722356;52102.47897521052;1300.8959283257316;1325.9337033148304;1356.5319964612193;1357.9524716634871;1366.0164681429355;27109.457810844066;28212;28650.426991459637;29675;29799;48394.51605484041;49698.503014969385;51280;51291.974160515834;53778.19332709934;614308;620423;621144;622569;623358;32113;32826.58361370347;32849.67150328466;35047.299054019284;35657.64342356647;36258.27483450369;36779.26441471209;43074.353884691125;43113.78443107764;45031.77484112558;34733.47899781455;34832.12919676927;34925.65074349224;35099.473507897914;36018.63981360153;34602.252560750414;34879.47680784739;35532.46701299431;35585.64414355927;35786.926392208276;34028;34248.65751342455;35597.46603800893;35679.41334714931;36305.81847090748;30366.696333036387;32097.839510802296;32168.678313217504;32259.838700806347;32487.700491980057;21052.736556566117;23213.976478271332;23792.88103559471;24072;24350.51298974046 23:13:14 INFO - PROCESS | 1635 | |2;jquery.html;318.23940781532133;333.7080054951917;344.82413968875863;369.3702237684748;398.7408184679958;12410;13207.405666744995;15191.86061045422;15710.450134245304;16066.839331606681;19104.522386940298;19327.420177394702;19494.805051949475;19654.901725491352;19850.801491985076;5007.173816320312;5059.2916991621205;5233.267342572043;5309.522143007129;5319.521243088121;4409.801558929856;4708.105459962613;5122.410922743877;5252.737363131828;5261.105612045962;4716.9292460613115;4733.171694953387;4737.928931066037;4746.098241334135;4757.690750101249;4556.77216139195;4738.881527961794;4740.028294199681;4750.524947505256;4850.466448690641;4721;4736.076465089309;4779.307000484945;4787.066522028206;4800.735959522225;11992;12026.759464810742;12078;12203.328816915066;12289.016878649687;2106.3996760923214;2183.5087105401317;2199.9450013749624;2520.911768088108;2546.2870396289068;8870.334724895658;8995.640174393016;8997.235235005033;9507.57215925285;9530.285228607881;1608.8230294667578;1644.6792875389303;1677.8993260404416;1678.3538337740058;1691.974620380683;113033;113707.43146284216;119609;121409;123844.38077809733;11170.720731981766;11235.382053986945;11348.14888883337;11407.543698252142;11647.30116193031;12480.126391152713;13332.6666833328;13338.599842004756;13585.184888906697;14478.986470946933;6560.185028378463;7070.328318809705;7933.087694915043;8392.748217553482;8504.659813607386;6799.93200067993;8143.063547692092;8213.726872334797;8281.751547453587;8374.288185504178;3732.64539868712;4044.15072834708;4267.975685835378;4280.993966417889;4292.313229883203;1742.5992021834795;1778.4575704410279;1841.7714565375777;1932.8646994710516;1977.9109940052733;872.3326655108847;875.8292133034124;885.2431171348533;904.556767184075;909.9272058235325;19880.403587892386;20140.597188056156;20974.370768876957;21032.26387076445;21387.46531336729;868.0148031983744;896.4576431259125;913.5523593439167;930.1721467893581;1012.792377562598;10254.179665626732;10770.24608277413;10785.946070269756;10794.946025269981;10810.513526891309;572994;576025;590804;591084;593407;124985.37507312406;132899.12928054272;134002;135958.32020839833;139762.3011884934;933.3419938943028;954.7947191353892;956.933014688979;964.8215080209903;967.1247520993653;18186.727199091758;18426.81573184251;18533.629327413106;18569.44291671252;18757.90621046886;40520.3921941177;50084.74957625189;55229.1715624274;55927.72036139793;56844;73197.6340118296;74676.62661686656;76056.85914711173;78630;82412;7852.097008844056;7883.208785109633;7895.368370530458;8065.153158918229;8151.551664658264;9142.77143071402;12733.808992865299;12786.61640150797;12991.675208119494;13232.933835330761;9776.315657904162;10792.460376981277;10883.945580272364;11349.092072634005;11411.714707132056;10816.4050977197;10976.286541374786;11141.832872506757;11196;11463.828042579535;10885.727856803644;11286.604968826052;11305;11305.773884522428;11886.643400697993;8001.8399632008195;8140.83718325642;8252;8255.42212045151;8395.99248090233;10429.582816687247;10826.242163048511;10847;11250;11348.602798901999 23:13:14 INFO - PROCESS | 1635 | |3;mootools.html;1522.9923850380746;1529.9694006119878;1715.5196544967414;1839.9632007359862;1866.2628261836567;7687.538747675137;8058.476199047058;8058.597070146491;8071.757847264576;8072.313853322467;7112.110986126734;7418.332350088491;7720.112187098485;7946.284834364906;7947;2018.0515157875811;2056.434480517862;2056.578401427711;2058.804413580715;2060.3406909788873;1842.7235914612781;2049.354453347191;2092.8325733941324;2104.2740254612136;2107.92622258221;1660.4271526323398;1790.337575097216;1895.3366321787332;1898.810118988104;2044.94887627809;1863.4409677096817;1873.8594605404649;1990.6118306930155;2069.606774712815;2070.440980935154;2082.7084208210863;2087.77034526202;2088.1438610169907;2088.8015638514316;2088.9164433422643;7966.681332746683;8029.919700802976;8038.196180381973;8043.678252869879;8066.798330041737;4989.201727723546;5164.715940623264;5166.793328266865;5172.948270517284;5181.715005674687;2739.2467071555484;2743.533599288126;3040.467918114321;3396.813175275359;3406.9659303407143;793.8491686579589;966.8646389505475;985.3545927417553;1002.8796544414716;1012.817692815293;10875.966783155587;10888.564457421693;10892.727681807939;10917.836232456519;10953.561857525689;3979.4030895365927;4504.25679762842;4518.2996635520885;4525.570070843265;4625.699329543569;9431.90568094324;9440.480773557383;9445.291603129655;9455.621775129055;9469.337146399686;5188.066148093304;5196;5203.505666961632;5204.7918083276245;5207.1668533034535;4511.932321015187;4717.622590192776;4729.337892695025;4807.663463557517;4816.422029356429;3646.2342907989087;3717.8698745543775;3758.361078616642;3787.242551489713;3796.0699628591174;1071.9678409647722;1241.391718058145;1256.0516809808537;1270.9428075736614;1276.9553065642845;820.5076953827775;843.890294261742;861.3668953319306;912.7632058560555;921.4931787516973;5245.790168393221;5368.7383464885725;5461.808836690697;5662.292213473316;5699.857503562444;765.4871236271712;907.328576853124;945.1824172091092;960.8174446855215;981.4405788700373;3107.8912238071557;3521.2781379817075;3637.181634132299;3679.6136405677553;3795.582485926546;8570.785730356667;9912.306138570375;10075.697729068139;10215.02957219063;10239.84640230397;10202.897971020344;10224.284300098921;10226.795464090677;10229.130523905402;10303.3817970922;7276.417886569062;7768.922310776933;7859.6070196489945;7876.960615196887;7881.133075361706;5194.22086687;5414.29614150158;5449.972750136224;5572.3034620672415;5731.054376028075;8329.083800781787;9216.585253663736;9288.489133097473;9386.812263754824;9466.384684995453;9008.954955225445;9314.860277095713;9413.058694130532;9506.636464790994;9521.333506654468;6989.825254368478;7188.60462674537;7196.0645116134565;7200.639968001474;7214.314640109181;6823.454123670193;7521.323080922524;7715.382769378324;7798.766037018899;7878.645460954156;7095.574265544084;7161.4270858330165;7197.676104575202;7219.639018049182;7226.385757210485;6960;7008.614526201108;7039.718411263492;7113;7128.857422851617;6357.55497115216;6434.099226108255;6504.60972341661;6505.316941721051;6508.479321654351;4398.912021759611;4542.136993971135;4713.646476514206;4814.350062741485;4825.782839772149;4694.812207511662;4772.809087636456;4881.658283920092;4899.363082799213;5059.848204553869 23:13:15 INFO - PROCESS | 1635 | |4;prototype.html;299.69730572122165;299.88154678901833;305.56914750202213;312.0778100712395;314.6177394465724;4498.955010449895;5058.190689489682;5237.79048838046;5281.392639846419;5366.382865970415;4204.591461860273;4739.606498718151;5111.974440127803;5184.9077334132435;5185.922211166826;3428.4171690812495;3460.307938412315;4047.919041619166;4049.7040946897005;4052.331365324725;3712.814359282039;3813.580506144322;3951.9209615807667;4067.532233793122;4070.5115386153557;3651.3245049665898;3775.622437756216;3919.9804000979957;3990.7805070721097;4039.0104424415913;3693.169036966688;3998.8000599969887;4000.6199411055904;4031.9516925599264;4036.576159503239;2901.825890446559;3417.837935102077;3431.571053618298;3450.5686789151355;3469.410200265961;5103.974480127576;5109.066019165259;5113.61647876407;5117.641765076446;5172.853265894649;3732.869349572779;4066.8373265069363;4521.394904808812;4524.253498172798;4561.657875659305;4682.976585117052;4879.756012199411;5167.586593072583;5445.50990410861;5540.119138048192;3792.1088544192094;3795.8102094895144;3805.6955443564448;3807.257584770971;3810.2379524095013;4868.391451068616;5763.904858076952;5767.798127065533;5812.767489300423;5814.825555233349;3722.4923905818187;3827.904302392407;3864.4976153099915;3889.124946886984;3910;3563.055790215595;3591.425371940477;3682.963170368262;3750.456183853326;3933.9803300983885;4213.431186789805;4500.819967201341;4503.572160644733;4518.615917646971;4588.242939914943;4614.377059096979;5523.337199536001;5547.252615426209;5588.6527658383575;5610.691411972381;1183.378726168751;1185.324365111878;1207.9396030198454;1227.9439681873582;1248.6826398149858;713.9500234983501;779.773865578987;832.98334033319;847.3348421489102;858.4034096303134;4363.127374525107;4428.2914733642465;4531.818727250873;4876.635411306931;4931.679440836334;4712.882177945578;4751.477337492942;4792.729926569462;5128.743562821918;5392.389506971483;4695.5539223773685;4815.855524334275;4858.659893807399;4929.556339929423;4932.457429682732;2227.9220227291967;2430.489597184576;2829.971700283012;2840.0769749830974;2851.700571439969;4820.301056346881;5094.286799848024;5234.785997272233;5398.460153984649;5473.370562385297;4822.565969062731;4859.829905953275;5228.424873263938;5420.804574147476;5489.094299440628;4868.707877527359;5265.552428043659;5479.917801233064;5534.889302214014;5575.675230155192;4794.770431749113;5269.998700246941;5336.866578335573;5359.356877174608;5399.325084364455;4292.441982542375;4386.279662907253;4750.482244410468;5137.691738495702;5328.49379308965;5547.389787123332;5574.358948720949;5576.386597474194;5580.302462192226;5623.831285061455;3244.837758112132;3556.87550935716;3607.6753092221825;3621.4748861415474;3737.3085979093953;4782.402199725035;5115.907274180715;5294.655847369909;5582.553395728413;5687.232223649754;4772;5551.750171242223;5551.8889622208135;5575.581831362583;5585.832425027255;4639.605633521189;4940.530649588283;4955.4301255356095;5039.110333624891;5106.617003724661;5233.450487698737;5317.25558421829;5382.596305277199;5415.891682166413;5418.157826339064;4553.453585569753;5478.424765399575;5483.793565415602;5564;5577.60956733041;4928.014397120662;5090.516400941905;5366.060939335523;5416.918746218731;5424.294841670558 23:13:15 INFO - PROCESS | 1635 | |5;yui.html;670.5205777868824;685.1675214614243;688.8713527785638;707.5542408282781;769.2230846844685;13188.34058297085;13448.798268025987;13772.724545509083;13782.448702051906;14076;12620.368981550931;13006;13015.869841301585;13037.413316400762;13037.934810325934;790.5691398187972;810.2586133687669;813.8982627171604;828.7223780033665;829.4152622401193;652.686710379016;676.4453148418299;792.13657113746;817.7628487738549;824.6536454689015;862.779991102268;873.9905409252324;875.0243478521435;875.2385424680537;876.7983363826323;868.5657171414324;868.6047848229073;868.8001759595328;870.3689824876977;871.2724874729608;499.22525422124335;514.0823629820752;614.0420943328378;647.2621211818531;647.9147428057984;12160.148789584733;12416.56542021034;12421.75156496865;12422.689432764162;12429.37853107341;10197.89802101977;10399.532021058994;10446.68659940203;10571.101456376216;10709.196810239186;9662.3719458235;9699.127078562893;9751.707448776548;9800.55897484615;10054.9497252514;2991.1176203020163;3040.6047213862275;3044.6955304469375;3081.337512434815;3103.5810165627577;96055;97452.025479745;100152.49923750407;100291.49854250756;100465;7805.80485487856;7964.141036002725;8176.836463270702;8264.049634292134;8418.242358187792;16555.66888662244;16722;16744;16854.325826966782;17080.48758537246;7161.534500257467;7279.708811647475;7434.405247580181;7611.809704757426;7631.313181813553;4052.250333688277;4146.232946904832;4159.334506478949;4209.284421648266;4243.978780106079;3230.2731885326084;3327.1349449143395;3349.129226401153;3357.3788849063003;3365.4447016242048;562.6624025584616;628.5914155798768;628.7547856336032;635.939585739354;639.6993413095837;576.5502907731976;580.8576898659771;594.6135012242078;631.2456614345905;640.9647469389135;7926.326262267657;8279.172082791792;8426.368022398345;8543.429265343011;8625.568721564023;549.3353042818235;556.3849905444428;600.5135839969638;614.4255121461448;629.6064959400375;4073.5315438724333;4188.099558594917;4189.49726032878;4191.97904010478;4341.522432532419;70051.64974175097;70144.94782578266;70391;71071.0978393818;84507.57746211352;94311.52844235735;96058.55912161323;96314.51842740741;98183;98713;81542;84877.30245395057;85169.57415212884;85555.5722221385;86624;7069.222385537484;8234.300084492885;8250.504969701837;8376.83246335082;8461.703840365557;48758.75620621874;51199.48800511947;51395.22907156321;54043;54472.18291725548;59583;69109.65445172942;76074.85887711578;76704.23295766971;77128;11146;11286.49210785533;11329.206955513364;11593.130515211224;11713.414329283672;12104.697382565506;12274.938625306817;12280.570180043656;12405.875941240472;12810.359482026048;11513.424328783694;11600.477978491157;11710.648680539598;11868.287902725864;11868.584599538975;11680.94159529197;11692.415379231175;11755.823662644896;11871.287722736663;12218.816717749418;11405.372704501;11524.250923689933;11656.18406711522;11692.060866852577;12156.635300940987;9357.251419886528;9537.284703647392;10014;10017.098461138532;10116.342437741523;8306.418550701635;8560.871586926076;9864;9887.703368898945;9891.653792117242 23:13:15 INFO - PROCESS | 1635 | __end_tp_report 23:13:15 INFO - PROCESS | 1635 | __start_cc_report 23:13:15 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,1717 23:13:15 INFO - PROCESS | 1635 | __end_cc_report 23:13:15 INFO - PROCESS | 1635 | __startTimestamp1479366794988__endTimestamp 23:13:15 INFO - PROCESS | 1635 | 23:13:15 INFO - PROCESS | 1635 | ------- Summary: start ------- 23:13:15 INFO - PROCESS | 1635 | Number of tests: 6 23:13:15 INFO - PROCESS | 1635 | 23:13:15 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5704.74 Median:5936.50 stddev:654.54 (11.0%) stddev-sans-first:611.63 23:13:15 INFO - PROCESS | 1635 | Values: 2666.7 3127.3 3314.4 3353.7 3380.8 5549.5 5667.7 5669.7 5739.3 5749.3 5591.1 5670.2 5670.7 5681.8 5688.5 5116.4 5152.0 5163.5 5191.5 5192.1 5274.7 5277.3 5287.2 5288.8 5291.2 5194.8 5223.9 5242.4 5265.8 5272.0 5258.3 5284.0 5285.0 5286.0 5294.9 5036.3 5142.0 5206.5 5215.2 5238.6 4794.4 5504.3 5573.7 5599.2 5651.0 6185.4 6190.1 6206.1 6223.3 6250.7 6233.4 6265.0 6298.9 6304.7 6315.4 5657.8 5707.0 5709.4 5726.3 5795.8 6254.6 6264.0 6268.7 6278.1 6283.5 6098.0 6101.0 6101.7 6107.5 6113.9 6076.7 6085.2 6107.0 6143.0 6245.8 6131.7 6173.9 6175.8 6179.7 6186.2 6152.1 6216.9 6217.1 6219.3 6246.2 4836.3 4861.6 4916.2 4935.5 4948.1 4319.3 4442.6 4463.8 4473.8 4479.0 5677.9 5694.8 5696.7 5700.4 5715.9 6144.9 6192.4 6208.2 6209.0 6211.0 5681.1 5709.9 5741.0 5777.6 5782.2 5365.6 5390.4 5396.3 5400.5 5429.7 6261.8 6264.1 6265.0 6279.8 6285.7 6136.0 6150.5 6163.4 6188.3 6256.0 5398.4 6270.9 6284.8 6289.8 6295.0 6162.2 6177.4 6182.8 6190.4 6197.5 6107.8 6123.3 6155.0 6294.4 6299.7 5968.6 6038.5 6060.3 6068.2 6092.3 6058.5 6069.5 6072.0 6083.4 6087.5 5040.2 5678.0 5790.7 5875.9 5900.9 6022.2 6054.2 6086.2 6089.6 6093.3 5933.9 5936.5 5940.7 5954.6 5984.0 23:13:15 INFO - PROCESS | 1635 | 23:13:15 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:67547.16 Median:34986.47 stddev:153690.12 (439.3%) stddev-sans-first:154038.77 23:13:15 INFO - PROCESS | 1635 | Values: 477.6 477.7 492.9 504.9 516.8 31701.4 32972.8 33030.5 33049.8 34541.3 27587.9 33145.8 33193.5 33233.0 33233.5 6202.6 7204.5 7394.1 7397.9 7401.1 6257.5 7346.6 7570.2 7581.3 7657.4 7568.9 7613.3 7617.2 7628.3 7646.7 6041.2 6238.8 7524.1 7564.1 7568.7 7364.7 7623.9 7624.8 7694.3 7708.4 31530.0 31844.7 31898.8 31909.0 31911.0 3212.6 3229.8 3286.3 3403.2 3641.4 21236.9 21555.8 21992.5 22023.2 22152.9 2744.4 2792.8 2799.3 2800.4 2811.8 477306.0 477609.0 482783.0 483330.6 483882.0 58961.0 59475.0 59561.5 59579.0 59617.7 33507.0 34328.5 34641.8 35594.3 35746.8 14655.1 14735.0 14747.3 14754.6 14785.0 10946.1 11109.4 11130.4 11917.0 12006.6 18363.5 18677.8 18822.7 19105.8 19152.9 6212.7 7612.4 7634.3 7657.4 7663.3 879.7 948.2 965.9 966.7 995.2 28039.7 29109.4 29374.0 29503.3 29745.0 999.8 1030.2 1035.7 1044.6 1099.2 14625.5 15094.7 15255.2 15293.0 15488.5 601024.0 606732.0 607478.5 610717.0 611146.0 51294.7 51884.7 52059.0 52099.2 52102.5 1300.9 1325.9 1356.5 1358.0 1366.0 27109.5 28212.0 28650.4 29675.0 29799.0 48394.5 49698.5 51280.0 51292.0 53778.2 614308.0 620423.0 621144.0 622569.0 623358.0 32113.0 32826.6 32849.7 35047.3 35657.6 36258.3 36779.3 43074.4 43113.8 45031.8 34733.5 34832.1 34925.7 35099.5 36018.6 34602.3 34879.5 35532.5 35585.6 35786.9 34028.0 34248.7 35597.5 35679.4 36305.8 30366.7 32097.8 32168.7 32259.8 32487.7 21052.7 23214.0 23792.9 24072.0 24350.5 23:13:15 INFO - PROCESS | 1635 | 23:13:15 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:33918.74 Median:339.27 stddev:98068.56 (28906.1%) stddev-sans-first:98311.22 23:13:15 INFO - PROCESS | 1635 | Values: 318.2 333.7 344.8 369.4 398.7 12410.0 13207.4 15191.9 15710.5 16066.8 19104.5 19327.4 19494.8 19654.9 19850.8 5007.2 5059.3 5233.3 5309.5 5319.5 4409.8 4708.1 5122.4 5252.7 5261.1 4716.9 4733.2 4737.9 4746.1 4757.7 4556.8 4738.9 4740.0 4750.5 4850.5 4721.0 4736.1 4779.3 4787.1 4800.7 11992.0 12026.8 12078.0 12203.3 12289.0 2106.4 2183.5 2199.9 2520.9 2546.3 8870.3 8995.6 8997.2 9507.6 9530.3 1608.8 1644.7 1677.9 1678.4 1692.0 113033.0 113707.4 119609.0 121409.0 123844.4 11170.7 11235.4 11348.1 11407.5 11647.3 12480.1 13332.7 13338.6 13585.2 14479.0 6560.2 7070.3 7933.1 8392.7 8504.7 6799.9 8143.1 8213.7 8281.8 8374.3 3732.6 4044.2 4268.0 4281.0 4292.3 1742.6 1778.5 1841.8 1932.9 1977.9 872.3 875.8 885.2 904.6 909.9 19880.4 20140.6 20974.4 21032.3 21387.5 868.0 896.5 913.6 930.2 1012.8 10254.2 10770.2 10785.9 10794.9 10810.5 572994.0 576025.0 590804.0 591084.0 593407.0 124985.4 132899.1 134002.0 135958.3 139762.3 933.3 954.8 956.9 964.8 967.1 18186.7 18426.8 18533.6 18569.4 18757.9 40520.4 50084.7 55229.2 55927.7 56844.0 73197.6 74676.6 76056.9 78630.0 82412.0 7852.1 7883.2 7895.4 8065.2 8151.6 9142.8 12733.8 12786.6 12991.7 13232.9 9776.3 10792.5 10883.9 11349.1 11411.7 10816.4 10976.3 11141.8 11196.0 11463.8 10885.7 11286.6 11305.0 11305.8 11886.6 8001.8 8140.8 8252.0 8255.4 8396.0 10429.6 10826.2 10847.0 11250.0 11348.6 23:13:15 INFO - PROCESS | 1635 | 23:13:15 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5323.43 Median:5191.14 stddev:3000.62 (57.8%) stddev-sans-first:2995.45 23:13:15 INFO - PROCESS | 1635 | Values: 1523.0 1530.0 1715.5 1840.0 1866.3 7687.5 8058.5 8058.6 8071.8 8072.3 7112.1 7418.3 7720.1 7946.3 7947.0 2018.1 2056.4 2056.6 2058.8 2060.3 1842.7 2049.4 2092.8 2104.3 2107.9 1660.4 1790.3 1895.3 1898.8 2044.9 1863.4 1873.9 1990.6 2069.6 2070.4 2082.7 2087.8 2088.1 2088.8 2088.9 7966.7 8029.9 8038.2 8043.7 8066.8 4989.2 5164.7 5166.8 5172.9 5181.7 2739.2 2743.5 3040.5 3396.8 3407.0 793.8 966.9 985.4 1002.9 1012.8 10876.0 10888.6 10892.7 10917.8 10953.6 3979.4 4504.3 4518.3 4525.6 4625.7 9431.9 9440.5 9445.3 9455.6 9469.3 5188.1 5196.0 5203.5 5204.8 5207.2 4511.9 4717.6 4729.3 4807.7 4816.4 3646.2 3717.9 3758.4 3787.2 3796.1 1072.0 1241.4 1256.1 1270.9 1277.0 820.5 843.9 861.4 912.8 921.5 5245.8 5368.7 5461.8 5662.3 5699.9 765.5 907.3 945.2 960.8 981.4 3107.9 3521.3 3637.2 3679.6 3795.6 8570.8 9912.3 10075.7 10215.0 10239.8 10202.9 10224.3 10226.8 10229.1 10303.4 7276.4 7768.9 7859.6 7877.0 7881.1 5194.2 5414.3 5450.0 5572.3 5731.1 8329.1 9216.6 9288.5 9386.8 9466.4 9009.0 9314.9 9413.1 9506.6 9521.3 6989.8 7188.6 7196.1 7200.6 7214.3 6823.5 7521.3 7715.4 7798.8 7878.6 7095.6 7161.4 7197.7 7219.6 7226.4 6960.0 7008.6 7039.7 7113.0 7128.9 6357.6 6434.1 6504.6 6505.3 6508.5 4398.9 4542.1 4713.6 4814.4 4825.8 4694.8 4772.8 4881.7 4899.4 5059.8 23:13:15 INFO - PROCESS | 1635 | 23:13:15 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4334.01 Median:4818.08 stddev:1322.49 (27.4%) stddev-sans-first:1291.07 23:13:15 INFO - PROCESS | 1635 | Values: 299.7 299.9 305.6 312.1 314.6 4499.0 5058.2 5237.8 5281.4 5366.4 4204.6 4739.6 5112.0 5184.9 5185.9 3428.4 3460.3 4047.9 4049.7 4052.3 3712.8 3813.6 3951.9 4067.5 4070.5 3651.3 3775.6 3920.0 3990.8 4039.0 3693.2 3998.8 4000.6 4032.0 4036.6 2901.8 3417.8 3431.6 3450.6 3469.4 5104.0 5109.1 5113.6 5117.6 5172.9 3732.9 4066.8 4521.4 4524.3 4561.7 4683.0 4879.8 5167.6 5445.5 5540.1 3792.1 3795.8 3805.7 3807.3 3810.2 4868.4 5763.9 5767.8 5812.8 5814.8 3722.5 3827.9 3864.5 3889.1 3910.0 3563.1 3591.4 3683.0 3750.5 3934.0 4213.4 4500.8 4503.6 4518.6 4588.2 4614.4 5523.3 5547.3 5588.7 5610.7 1183.4 1185.3 1207.9 1227.9 1248.7 714.0 779.8 833.0 847.3 858.4 4363.1 4428.3 4531.8 4876.6 4931.7 4712.9 4751.5 4792.7 5128.7 5392.4 4695.6 4815.9 4858.7 4929.6 4932.5 2227.9 2430.5 2830.0 2840.1 2851.7 4820.3 5094.3 5234.8 5398.5 5473.4 4822.6 4859.8 5228.4 5420.8 5489.1 4868.7 5265.6 5479.9 5534.9 5575.7 4794.8 5270.0 5336.9 5359.4 5399.3 4292.4 4386.3 4750.5 5137.7 5328.5 5547.4 5574.4 5576.4 5580.3 5623.8 3244.8 3556.9 3607.7 3621.5 3737.3 4782.4 5115.9 5294.7 5582.6 5687.2 4772.0 5551.8 5551.9 5575.6 5585.8 4639.6 4940.5 4955.4 5039.1 5106.6 5233.5 5317.3 5382.6 5415.9 5418.2 4553.5 5478.4 5483.8 5564.0 5577.6 4928.0 5090.5 5366.1 5416.9 5424.3 23:13:15 INFO - PROCESS | 1635 | 23:13:15 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:19016.04 Median:30091.76 stddev:28357.22 (94.2%) stddev-sans-first:28403.31 23:13:15 INFO - PROCESS | 1635 | Values: 670.5 685.2 688.9 707.6 769.2 13188.3 13448.8 13772.7 13782.4 14076.0 12620.4 13006.0 13015.9 13037.4 13037.9 790.6 810.3 813.9 828.7 829.4 652.7 676.4 792.1 817.8 824.7 862.8 874.0 875.0 875.2 876.8 868.6 868.6 868.8 870.4 871.3 499.2 514.1 614.0 647.3 647.9 12160.1 12416.6 12421.8 12422.7 12429.4 10197.9 10399.5 10446.7 10571.1 10709.2 9662.4 9699.1 9751.7 9800.6 10054.9 2991.1 3040.6 3044.7 3081.3 3103.6 96055.0 97452.0 100152.5 100291.5 100465.0 7805.8 7964.1 8176.8 8264.0 8418.2 16555.7 16722.0 16744.0 16854.3 17080.5 7161.5 7279.7 7434.4 7611.8 7631.3 4052.3 4146.2 4159.3 4209.3 4244.0 3230.3 3327.1 3349.1 3357.4 3365.4 562.7 628.6 628.8 635.9 639.7 576.6 580.9 594.6 631.2 641.0 7926.3 8279.2 8426.4 8543.4 8625.6 549.3 556.4 600.5 614.4 629.6 4073.5 4188.1 4189.5 4192.0 4341.5 70051.6 70144.9 70391.0 71071.1 84507.6 94311.5 96058.6 96314.5 98183.0 98713.0 81542.0 84877.3 85169.6 85555.6 86624.0 7069.2 8234.3 8250.5 8376.8 8461.7 48758.8 51199.5 51395.2 54043.0 54472.2 59583.0 69109.7 76074.9 76704.2 77128.0 11146.0 11286.5 11329.2 11593.1 11713.4 12104.7 12274.9 12280.6 12405.9 12810.4 11513.4 11600.5 11710.6 11868.3 11868.6 11680.9 11692.4 11755.8 11871.3 12218.8 11405.4 11524.3 11656.2 11692.1 12156.6 9357.3 9537.3 10014.0 10017.1 10116.3 8306.4 8560.9 9864.0 9887.7 9891.7 23:13:15 INFO - PROCESS | 1635 | -------- Summary: end -------- 23:13:15 INFO - PROCESS | 1635 | 23:13:15 INFO - TEST-INFO | 1635: exit 0 23:13:15 INFO - TEST-OK | dromaeo_css | took 1119585ms 23:13:15 INFO - TEST-START | kraken 23:13:15 INFO - operating with platform_type : mac_ 23:13:15 INFO - Initialising browser for kraken test... 23:13:15 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpPOIVO5/profile http://localhost:49244/getInfo.html) 23:13:17 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 23:13:17 INFO - PROCESS | 1645 | colorDepth:24 23:13:17 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 23:13:17 INFO - PROCESS | 1645 | __metrics 23:13:18 INFO - PROCESS | 1645 | 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" 23:13:18 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:13:18 INFO - TEST-INFO | 1645: exit 0 23:13:18 INFO - Browser initialized. 23:13:18 INFO - Running cycle 1/1 for kraken test... 23:13:18 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpPOIVO5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:13:30 INFO - PROCESS | 1646 | RSS: Main: 237658112 23:13:30 INFO - PROCESS | 1646 | 23:13:33 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 23:13:34 INFO - PROCESS | 1646 | RSS: Main: 288276480 23:13:34 INFO - PROCESS | 1646 | 23:13:37 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 23:13:37 INFO - PROCESS | 1646 | RSS: Main: 289665024 23:13:37 INFO - PROCESS | 1646 | 23:13:41 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 23:13:41 INFO - PROCESS | 1646 | RSS: Main: 376651776 23:13:41 INFO - PROCESS | 1646 | 23:13:44 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 23:13:44 INFO - PROCESS | 1646 | RSS: Main: 281456640 23:13:44 INFO - PROCESS | 1646 | 23:13:46 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:13:47 INFO - PROCESS | 1646 | RSS: Main: 286564352 23:13:47 INFO - PROCESS | 1646 | 23:13:50 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 23:13:50 INFO - PROCESS | 1646 | RSS: Main: 328019968 23:13:50 INFO - PROCESS | 1646 | 23:13:53 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 23:13:54 INFO - PROCESS | 1646 | RSS: Main: 329170944 23:13:54 INFO - PROCESS | 1646 | 23:13:57 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 23:13:57 INFO - PROCESS | 1646 | RSS: Main: 326848512 23:13:57 INFO - PROCESS | 1646 | 23:13:59 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:13:59 INFO - PROCESS | 1646 | RSS: Main: 303292416 23:13:59 INFO - PROCESS | 1646 | 23:14:01 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:14:02 INFO - PROCESS | 1646 | RSS: Main: 307126272 23:14:02 INFO - PROCESS | 1646 | 23:14:04 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:14:05 INFO - PROCESS | 1646 | RSS: Main: 309239808 23:14:05 INFO - PROCESS | 1646 | 23:14:08 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:14:09 INFO - PROCESS | 1646 | RSS: Main: 317706240 23:14:09 INFO - PROCESS | 1646 | 23:14:12 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:14:12 INFO - PROCESS | 1646 | RSS: Main: 317628416 23:14:12 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 23:14:14 INFO - PROCESS | 1646 | RSS: Main: 360837120 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | __start_tp_report 23:14:14 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 23:14:14 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 23:14:14 INFO - PROCESS | 1646 | |i|pagename|runs| 23:14:14 INFO - PROCESS | 1646 | |0;ai-astar;114;115;115;114;115;116;114;118;161;152 23:14:14 INFO - PROCESS | 1646 | |1;audio-beat-detection;145;134;136;148;114;113;111;111;149;138 23:14:14 INFO - PROCESS | 1646 | |2;audio-dft;183;187;183;185;183;183;179;180;183;183 23:14:14 INFO - PROCESS | 1646 | |3;audio-fft;94;50;58;107;84;81;59;58;84;58 23:14:14 INFO - PROCESS | 1646 | |4;audio-oscillator;88;71;92;70;69;69;90;68;69;92 23:14:14 INFO - PROCESS | 1646 | |5;imaging-gaussian-blur;89;87;90;87;90;86;90;88;89;86 23:14:14 INFO - PROCESS | 1646 | |6;imaging-darkroom;95;108;145;146;95;94;95;95;95;94 23:14:14 INFO - PROCESS | 1646 | |7;imaging-desaturate;85;85;82;86;85;88;87;86;87;87 23:14:14 INFO - PROCESS | 1646 | |8;json-parse-financial;56;56;53;55;55;54;56;55;55;55 23:14:14 INFO - PROCESS | 1646 | |9;json-stringify-tinderbox;65;74;81;74;72;93;98;98;83;70 23:14:14 INFO - PROCESS | 1646 | |10;stanford-crypto-aes;88;87;87;89;89;87;88;84;87;86 23:14:14 INFO - PROCESS | 1646 | |11;stanford-crypto-ccm;147;149;146;150;138;154;150;149;189;152 23:14:14 INFO - PROCESS | 1646 | |12;stanford-crypto-pbkdf2;181;230;195;173;174;176;173;225;221;176 23:14:14 INFO - PROCESS | 1646 | |13;stanford-crypto-sha256-iterative;68;68;68;67;68;66;68;66;67;66 23:14:14 INFO - PROCESS | 1646 | __end_tp_report 23:14:14 INFO - PROCESS | 1646 | __start_cc_report 23:14:14 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,2047 23:14:14 INFO - PROCESS | 1646 | __end_cc_report 23:14:14 INFO - PROCESS | 1646 | __startTimestamp1479366854853__endTimestamp 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | ------- Summary: start ------- 23:14:14 INFO - PROCESS | 1646 | Number of tests: 14 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | [#0] ai-astar Cycles:10 Average:123.40 Median:115.50 stddev:17.61 (15.3%) stddev-sans-first:18.35 23:14:14 INFO - PROCESS | 1646 | Values: 114.0 115.0 115.0 114.0 115.0 116.0 114.0 118.0 161.0 152.0 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:137.00 stddev:15.96 (11.7%) stddev-sans-first:15.97 23:14:14 INFO - PROCESS | 1646 | Values: 145.0 134.0 136.0 148.0 114.0 113.0 111.0 111.0 149.0 138.0 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.00 stddev:2.23 (1.2%) stddev-sans-first:2.37 23:14:14 INFO - PROCESS | 1646 | Values: 183.0 187.0 183.0 185.0 183.0 183.0 179.0 180.0 183.0 183.0 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | [#3] audio-fft Cycles:10 Average:73.30 Median:70.00 stddev:19.15 (27.4%) stddev-sans-first:18.79 23:14:14 INFO - PROCESS | 1646 | Values: 94.0 50.0 58.0 107.0 84.0 81.0 59.0 58.0 84.0 58.0 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:79.50 stddev:11.01 (13.9%) stddev-sans-first:11.05 23:14:14 INFO - PROCESS | 1646 | Values: 88.0 71.0 92.0 70.0 69.0 69.0 90.0 68.0 69.0 92.0 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | [#5] imaging-gaussian-blur Cycles:10 Average:88.20 Median:89.00 stddev:1.62 (1.8%) stddev-sans-first:1.69 23:14:14 INFO - PROCESS | 1646 | Values: 89.0 87.0 90.0 87.0 90.0 86.0 90.0 88.0 89.0 86.0 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | [#6] imaging-darkroom Cycles:10 Average:106.20 Median:95.00 stddev:21.13 (22.2%) stddev-sans-first:22.02 23:14:14 INFO - PROCESS | 1646 | Values: 95.0 108.0 145.0 146.0 95.0 94.0 95.0 95.0 95.0 94.0 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:86.50 stddev:1.69 (1.9%) stddev-sans-first:1.76 23:14:14 INFO - PROCESS | 1646 | Values: 85.0 85.0 82.0 86.0 85.0 88.0 87.0 86.0 87.0 87.0 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:0.94 (1.7%) stddev-sans-first:0.93 23:14:14 INFO - PROCESS | 1646 | Values: 56.0 56.0 53.0 55.0 55.0 54.0 56.0 55.0 55.0 55.0 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | [#9] json-stringify-tinderbox Cycles:10 Average:80.80 Median:82.00 stddev:11.93 (14.6%) stddev-sans-first:11.20 23:14:14 INFO - PROCESS | 1646 | Values: 65.0 74.0 81.0 74.0 72.0 93.0 98.0 98.0 83.0 70.0 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:87.50 stddev:1.48 (1.7%) stddev-sans-first:1.54 23:14:14 INFO - PROCESS | 1646 | Values: 88.0 87.0 87.0 89.0 89.0 87.0 88.0 84.0 87.0 86.0 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | [#11] stanford-crypto-ccm Cycles:10 Average:152.40 Median:150.00 stddev:13.56 (9.0%) stddev-sans-first:14.24 23:14:14 INFO - PROCESS | 1646 | Values: 147.0 149.0 146.0 150.0 138.0 154.0 150.0 149.0 189.0 152.0 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.40 Median:188.00 stddev:23.71 (12.6%) stddev-sans-first:24.79 23:14:14 INFO - PROCESS | 1646 | Values: 181.0 230.0 195.0 173.0 174.0 176.0 173.0 225.0 221.0 176.0 23:14:14 INFO - PROCESS | 1646 | 23:14:14 INFO - PROCESS | 1646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:68.00 stddev:0.92 (1.4%) stddev-sans-first:0.93 23:14:14 INFO - PROCESS | 1646 | Values: 68.0 68.0 68.0 67.0 68.0 66.0 68.0 66.0 67.0 66.0 23:14:14 INFO - PROCESS | 1646 | -------- Summary: end -------- 23:14:14 INFO - PROCESS | 1646 | 23:14:15 INFO - TEST-INFO | 1646: exit 0 23:14:15 INFO - TEST-OK | kraken | took 60174ms 23:14:15 INFO - SUITE-END | took 1179s 23:14:16 INFO - Completed test suite (00:19:39) 23:14:16 INFO - javascript benchmark 23:14:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2666.746659067388, 3127.311991361901, 3314.3537010283, 3353.748468864834, 3380.7971521708714, 5549.500544950964, 5667.744951477183, 5669.659820410768, 5739.311282646083, 5749.252597162374, 5591.1054231323, 5670.177824215486, 5670.744816483258, 5681.7727290908315, 5688.516476099526, 5116.360454943132, 5151.974240128794, 5163.483651634825, 5191.480851914797, 5192.065428222918, 5274.683518988853, 5277.261183434322, 5287.206918962148, 5288.841334759944, 5291.153415453528, 5194.818181363653, 5223.921641175385, 5242.449542797989, 5265.763040663161, 5272.0, 5258.342707161605, 5284.021108231883, 5284.9958507883375, 5286.0, 5294.894102117936, 5036.269740887551, 5142.0, 5206.5314121729225, 5215.24378965048, 5238.607104467142, 4794.400699912511, 5504.311961004874, 5573.721313934287, 5599.160125981095, 5650.971745141289, 6185.35053819347, 6190.071489276599, 6206.131141640174, 6223.25320961483, 6250.749970001195, 6233.376662333776, 6264.9976003839165, 6298.905516417258, 6304.684765761694, 6315.431611155018, 5657.830265092054, 5706.9714651426475, 5709.400512946164, 5726.255586773693, 5795.768169273266, 6254.624722516573, 6263.96868015657, 6268.749250029948, 6278.121063051177, 6283.497320214372, 6097.969510152421, 6100.969495152584, 6101.694915254219, 6107.541934354941, 6113.938860611426, 6076.696165191723, 6085.17850090241, 6107.037103281039, 6143.0342290467515, 6245.843853903598, 6131.69422353494, 6173.9073913891325, 6175.84560385994, 6179.721912513947, 6186.1648677427975, 6152.077188421773, 6216.875662486725, 6217.067439883963, 6219.346968568235, 6246.156768836149, 4836.322914791932, 4861.562459378602, 4916.237983112624, 4935.457099719028, 4948.084604348207, 4319.308910574356, 4442.644588432918, 4463.754493502888, 4473.7986790594505, 4479.014616784302, 5677.914831277534, 5694.772209111672, 5696.658200507983, 5700.37295897451, 5715.914261286084, 6144.910730351612, 6192.411720886598, 6208.2239720034995, 6209.0, 6211.0372892201785, 5681.062624666966, 5709.857253568694, 5741.0, 5777.566682498829, 5782.190493330937, 5365.570754339644, 5390.407055223922, 5396.271503346998, 5400.486953739467, 5429.728513574306, 6261.843453913689, 6264.123022776906, 6265.028920517327, 6279.843003924847, 6285.685715714288, 6135.969320153371, 6150.477209437248, 6163.3836616338, 6188.288346840171, 6256.0, 5398.3791863934575, 6270.9372906270355, 6284.842878928063, 6289.779857704958, 6294.968525157345, 6162.198914141133, 6177.351378105234, 6182.814515564361, 6190.380961903774, 6197.4732147767945, 6107.765388666889, 6123.2652081750475, 6154.969225153845, 6294.402031806971, 6299.653519056313, 5968.582199246185, 6038.456538911519, 6060.272767267956, 6068.211132552917, 6092.299385570539, 6058.485028772604, 6069.544784141295, 6071.969640151771, 6083.422074902877, 6087.512998960161, 5040.193569028937, 5678.0, 5790.652560846194, 5875.94124058771, 5900.940990590211, 6022.1870047543025, 6054.212952316171, 6086.208792857077, 6089.5737298388685, 6093.268807743099, 5933.940660593512, 5936.495397891055, 5940.702964851826, 5954.642721436554, 5984.042553191469], "value": 5660.412564352676, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.5749582871243, 477.6775676418417, 492.86446227287513, 504.8611631801255, 516.8010316028328, 31701.36597268053, 32972.835135824345, 33030.50454243185, 33049.834750826245, 34541.30917381651, 27587.86206068972, 33145.834270828615, 33193.502097468496, 33233.0, 33233.501497477555, 6202.596831205957, 7204.531705439156, 7394.075740532434, 7397.889031664529, 7401.0748656417945, 6257.499400048008, 7346.632668366587, 7570.16728159902, 7581.317681408672, 7657.425693073014, 7568.886466703004, 7613.276738709814, 7617.238276172367, 7628.275313845176, 7646.694132234732, 6041.184440100574, 6238.844028899269, 7524.134724506697, 7564.092308922965, 7568.659410326521, 7364.668589913466, 7623.923760762377, 7624.809379765495, 7694.3069925525615, 7708.383329333696, 31530.0, 31844.681553184633, 31898.84050579732, 31909.0, 31911.0, 3212.6305474870455, 3229.83850807461, 3286.277019055804, 3403.2172600301824, 3641.3809652359164, 21236.893815530824, 21555.784442155535, 21992.45018874525, 22023.22918697838, 22152.88923555388, 2744.40995186036, 2792.7765778737653, 2799.2721892308145, 2800.425912687894, 2811.817231879921, 477306.0, 477609.0, 482783.0, 483330.5833470845, 483882.0312070348, 58961.0, 59475.0, 59561.51096222629, 59579.0, 59617.70191149016, 33507.0, 34328.50743880967, 34641.78753743607, 35594.28811423757, 35746.8212658935, 14655.120692758468, 14735.0, 14747.262636868329, 14754.557363279118, 14785.0, 10946.124310055176, 11109.44452777358, 11130.443477826077, 11917.0, 12006.63980080599, 18363.54091147732, 18677.813221867607, 18822.71765923512, 19105.80894191068, 19152.90423547901, 6212.658303793244, 7612.3633418814115, 7634.312911837961, 7657.425693073042, 7663.31030207284, 879.7140929198036, 948.170350942925, 965.9130678238993, 966.6665000574791, 995.2386424385351, 28039.719602804118, 29109.41781164365, 29374.0, 29503.26241843971, 29745.0, 999.7652898670136, 1030.2221822523945, 1035.7307100153864, 1044.5612842606124, 1099.1536516881956, 14625.488107916171, 15094.716949059453, 15255.165546427683, 15293.0, 15488.457903973307, 601024.0, 606732.0, 607478.5138965953, 610717.0, 611146.0, 51294.74352628213, 51884.740576297634, 52058.95882082337, 52099.218511722356, 52102.47897521052, 1300.8959283257316, 1325.9337033148304, 1356.5319964612193, 1357.9524716634871, 1366.0164681429355, 27109.457810844066, 28212.0, 28650.426991459637, 29675.0, 29799.0, 48394.51605484041, 49698.503014969385, 51280.0, 51291.974160515834, 53778.19332709934, 614308.0, 620423.0, 621144.0, 622569.0, 623358.0, 32113.0, 32826.58361370347, 32849.67150328466, 35047.299054019284, 35657.64342356647, 36258.27483450369, 36779.26441471209, 43074.353884691125, 43113.78443107764, 45031.77484112558, 34733.47899781455, 34832.12919676927, 34925.65074349224, 35099.473507897914, 36018.63981360153, 34602.252560750414, 34879.47680784739, 35532.46701299431, 35585.64414355927, 35786.926392208276, 34028.0, 34248.65751342455, 35597.46603800893, 35679.41334714931, 36305.81847090748, 30366.696333036387, 32097.839510802296, 32168.678313217504, 32259.838700806347, 32487.700491980057, 21052.736556566117, 23213.976478271332, 23792.88103559471, 24072.0, 24350.51298974046], "value": 17889.231785038748, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.23940781532133, 333.7080054951917, 344.82413968875863, 369.3702237684748, 398.7408184679958, 12410.0, 13207.405666744995, 15191.86061045422, 15710.450134245304, 16066.839331606681, 19104.522386940298, 19327.420177394702, 19494.805051949475, 19654.901725491352, 19850.801491985076, 5007.173816320312, 5059.2916991621205, 5233.267342572043, 5309.522143007129, 5319.521243088121, 4409.801558929856, 4708.105459962613, 5122.410922743877, 5252.737363131828, 5261.105612045962, 4716.9292460613115, 4733.171694953387, 4737.928931066037, 4746.098241334135, 4757.690750101249, 4556.77216139195, 4738.881527961794, 4740.028294199681, 4750.524947505256, 4850.466448690641, 4721.0, 4736.076465089309, 4779.307000484945, 4787.066522028206, 4800.735959522225, 11992.0, 12026.759464810742, 12078.0, 12203.328816915066, 12289.016878649687, 2106.3996760923214, 2183.5087105401317, 2199.9450013749624, 2520.911768088108, 2546.2870396289068, 8870.334724895658, 8995.640174393016, 8997.235235005033, 9507.57215925285, 9530.285228607881, 1608.8230294667578, 1644.6792875389303, 1677.8993260404416, 1678.3538337740058, 1691.974620380683, 113033.0, 113707.43146284216, 119609.0, 121409.0, 123844.38077809733, 11170.720731981766, 11235.382053986945, 11348.14888883337, 11407.543698252142, 11647.30116193031, 12480.126391152713, 13332.6666833328, 13338.599842004756, 13585.184888906697, 14478.986470946933, 6560.185028378463, 7070.328318809705, 7933.087694915043, 8392.748217553482, 8504.659813607386, 6799.93200067993, 8143.063547692092, 8213.726872334797, 8281.751547453587, 8374.288185504178, 3732.64539868712, 4044.15072834708, 4267.975685835378, 4280.993966417889, 4292.313229883203, 1742.5992021834795, 1778.4575704410279, 1841.7714565375777, 1932.8646994710516, 1977.9109940052733, 872.3326655108847, 875.8292133034124, 885.2431171348533, 904.556767184075, 909.9272058235325, 19880.403587892386, 20140.597188056156, 20974.370768876957, 21032.26387076445, 21387.46531336729, 868.0148031983744, 896.4576431259125, 913.5523593439167, 930.1721467893581, 1012.792377562598, 10254.179665626732, 10770.24608277413, 10785.946070269756, 10794.946025269981, 10810.513526891309, 572994.0, 576025.0, 590804.0, 591084.0, 593407.0, 124985.37507312406, 132899.12928054272, 134002.0, 135958.32020839833, 139762.3011884934, 933.3419938943028, 954.7947191353892, 956.933014688979, 964.8215080209903, 967.1247520993653, 18186.727199091758, 18426.81573184251, 18533.629327413106, 18569.44291671252, 18757.90621046886, 40520.3921941177, 50084.74957625189, 55229.1715624274, 55927.72036139793, 56844.0, 73197.6340118296, 74676.62661686656, 76056.85914711173, 78630.0, 82412.0, 7852.097008844056, 7883.208785109633, 7895.368370530458, 8065.153158918229, 8151.551664658264, 9142.77143071402, 12733.808992865299, 12786.61640150797, 12991.675208119494, 13232.933835330761, 9776.315657904162, 10792.460376981277, 10883.945580272364, 11349.092072634005, 11411.714707132056, 10816.4050977197, 10976.286541374786, 11141.832872506757, 11196.0, 11463.828042579535, 10885.727856803644, 11286.604968826052, 11305.0, 11305.773884522428, 11886.643400697993, 8001.8399632008195, 8140.83718325642, 8252.0, 8255.42212045151, 8395.99248090233, 10429.582816687247, 10826.242163048511, 10847.0, 11250.0, 11348.602798901999], "value": 8840.483105414167, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1522.9923850380746, 1529.9694006119878, 1715.5196544967414, 1839.9632007359862, 1866.2628261836567, 7687.538747675137, 8058.476199047058, 8058.597070146491, 8071.757847264576, 8072.313853322467, 7112.110986126734, 7418.332350088491, 7720.112187098485, 7946.284834364906, 7947.0, 2018.0515157875811, 2056.434480517862, 2056.578401427711, 2058.804413580715, 2060.3406909788873, 1842.7235914612781, 2049.354453347191, 2092.8325733941324, 2104.2740254612136, 2107.92622258221, 1660.4271526323398, 1790.337575097216, 1895.3366321787332, 1898.810118988104, 2044.94887627809, 1863.4409677096817, 1873.8594605404649, 1990.6118306930155, 2069.606774712815, 2070.440980935154, 2082.7084208210863, 2087.77034526202, 2088.1438610169907, 2088.8015638514316, 2088.9164433422643, 7966.681332746683, 8029.919700802976, 8038.196180381973, 8043.678252869879, 8066.798330041737, 4989.201727723546, 5164.715940623264, 5166.793328266865, 5172.948270517284, 5181.715005674687, 2739.2467071555484, 2743.533599288126, 3040.467918114321, 3396.813175275359, 3406.9659303407143, 793.8491686579589, 966.8646389505475, 985.3545927417553, 1002.8796544414716, 1012.817692815293, 10875.966783155587, 10888.564457421693, 10892.727681807939, 10917.836232456519, 10953.561857525689, 3979.4030895365927, 4504.25679762842, 4518.2996635520885, 4525.570070843265, 4625.699329543569, 9431.90568094324, 9440.480773557383, 9445.291603129655, 9455.621775129055, 9469.337146399686, 5188.066148093304, 5196.0, 5203.505666961632, 5204.7918083276245, 5207.1668533034535, 4511.932321015187, 4717.622590192776, 4729.337892695025, 4807.663463557517, 4816.422029356429, 3646.2342907989087, 3717.8698745543775, 3758.361078616642, 3787.242551489713, 3796.0699628591174, 1071.9678409647722, 1241.391718058145, 1256.0516809808537, 1270.9428075736614, 1276.9553065642845, 820.5076953827775, 843.890294261742, 861.3668953319306, 912.7632058560555, 921.4931787516973, 5245.790168393221, 5368.7383464885725, 5461.808836690697, 5662.292213473316, 5699.857503562444, 765.4871236271712, 907.328576853124, 945.1824172091092, 960.8174446855215, 981.4405788700373, 3107.8912238071557, 3521.2781379817075, 3637.181634132299, 3679.6136405677553, 3795.582485926546, 8570.785730356667, 9912.306138570375, 10075.697729068139, 10215.02957219063, 10239.84640230397, 10202.897971020344, 10224.284300098921, 10226.795464090677, 10229.130523905402, 10303.3817970922, 7276.417886569062, 7768.922310776933, 7859.6070196489945, 7876.960615196887, 7881.133075361706, 5194.22086687, 5414.29614150158, 5449.972750136224, 5572.3034620672415, 5731.054376028075, 8329.083800781787, 9216.585253663736, 9288.489133097473, 9386.812263754824, 9466.384684995453, 9008.954955225445, 9314.860277095713, 9413.058694130532, 9506.636464790994, 9521.333506654468, 6989.825254368478, 7188.60462674537, 7196.0645116134565, 7200.639968001474, 7214.314640109181, 6823.454123670193, 7521.323080922524, 7715.382769378324, 7798.766037018899, 7878.645460954156, 7095.574265544084, 7161.4270858330165, 7197.676104575202, 7219.639018049182, 7226.385757210485, 6960.0, 7008.614526201108, 7039.718411263492, 7113.0, 7128.857422851617, 6357.55497115216, 6434.099226108255, 6504.60972341661, 6505.316941721051, 6508.479321654351, 4398.912021759611, 4542.136993971135, 4713.646476514206, 4814.350062741485, 4825.782839772149, 4694.812207511662, 4772.809087636456, 4881.658283920092, 4899.363082799213, 5059.848204553869], "value": 4261.143063376959, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.69730572122165, 299.88154678901833, 305.56914750202213, 312.0778100712395, 314.6177394465724, 4498.955010449895, 5058.190689489682, 5237.79048838046, 5281.392639846419, 5366.382865970415, 4204.591461860273, 4739.606498718151, 5111.974440127803, 5184.9077334132435, 5185.922211166826, 3428.4171690812495, 3460.307938412315, 4047.919041619166, 4049.7040946897005, 4052.331365324725, 3712.814359282039, 3813.580506144322, 3951.9209615807667, 4067.532233793122, 4070.5115386153557, 3651.3245049665898, 3775.622437756216, 3919.9804000979957, 3990.7805070721097, 4039.0104424415913, 3693.169036966688, 3998.8000599969887, 4000.6199411055904, 4031.9516925599264, 4036.576159503239, 2901.825890446559, 3417.837935102077, 3431.571053618298, 3450.5686789151355, 3469.410200265961, 5103.974480127576, 5109.066019165259, 5113.61647876407, 5117.641765076446, 5172.853265894649, 3732.869349572779, 4066.8373265069363, 4521.394904808812, 4524.253498172798, 4561.657875659305, 4682.976585117052, 4879.756012199411, 5167.586593072583, 5445.50990410861, 5540.119138048192, 3792.1088544192094, 3795.8102094895144, 3805.6955443564448, 3807.257584770971, 3810.2379524095013, 4868.391451068616, 5763.904858076952, 5767.798127065533, 5812.767489300423, 5814.825555233349, 3722.4923905818187, 3827.904302392407, 3864.4976153099915, 3889.124946886984, 3910.0, 3563.055790215595, 3591.425371940477, 3682.963170368262, 3750.456183853326, 3933.9803300983885, 4213.431186789805, 4500.819967201341, 4503.572160644733, 4518.615917646971, 4588.242939914943, 4614.377059096979, 5523.337199536001, 5547.252615426209, 5588.6527658383575, 5610.691411972381, 1183.378726168751, 1185.324365111878, 1207.9396030198454, 1227.9439681873582, 1248.6826398149858, 713.9500234983501, 779.773865578987, 832.98334033319, 847.3348421489102, 858.4034096303134, 4363.127374525107, 4428.2914733642465, 4531.818727250873, 4876.635411306931, 4931.679440836334, 4712.882177945578, 4751.477337492942, 4792.729926569462, 5128.743562821918, 5392.389506971483, 4695.5539223773685, 4815.855524334275, 4858.659893807399, 4929.556339929423, 4932.457429682732, 2227.9220227291967, 2430.489597184576, 2829.971700283012, 2840.0769749830974, 2851.700571439969, 4820.301056346881, 5094.286799848024, 5234.785997272233, 5398.460153984649, 5473.370562385297, 4822.565969062731, 4859.829905953275, 5228.424873263938, 5420.804574147476, 5489.094299440628, 4868.707877527359, 5265.552428043659, 5479.917801233064, 5534.889302214014, 5575.675230155192, 4794.770431749113, 5269.998700246941, 5336.866578335573, 5359.356877174608, 5399.325084364455, 4292.441982542375, 4386.279662907253, 4750.482244410468, 5137.691738495702, 5328.49379308965, 5547.389787123332, 5574.358948720949, 5576.386597474194, 5580.302462192226, 5623.831285061455, 3244.837758112132, 3556.87550935716, 3607.6753092221825, 3621.4748861415474, 3737.3085979093953, 4782.402199725035, 5115.907274180715, 5294.655847369909, 5582.553395728413, 5687.232223649754, 4772.0, 5551.750171242223, 5551.8889622208135, 5575.581831362583, 5585.832425027255, 4639.605633521189, 4940.530649588283, 4955.4301255356095, 5039.110333624891, 5106.617003724661, 5233.450487698737, 5317.25558421829, 5382.596305277199, 5415.891682166413, 5418.157826339064, 4553.453585569753, 5478.424765399575, 5483.793565415602, 5564.0, 5577.60956733041, 4928.014397120662, 5090.516400941905, 5366.060939335523, 5416.918746218731, 5424.294841670558], "value": 3909.0860704662423, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.5205777868824, 685.1675214614243, 688.8713527785638, 707.5542408282781, 769.2230846844685, 13188.34058297085, 13448.798268025987, 13772.724545509083, 13782.448702051906, 14076.0, 12620.368981550931, 13006.0, 13015.869841301585, 13037.413316400762, 13037.934810325934, 790.5691398187972, 810.2586133687669, 813.8982627171604, 828.7223780033665, 829.4152622401193, 652.686710379016, 676.4453148418299, 792.13657113746, 817.7628487738549, 824.6536454689015, 862.779991102268, 873.9905409252324, 875.0243478521435, 875.2385424680537, 876.7983363826323, 868.5657171414324, 868.6047848229073, 868.8001759595328, 870.3689824876977, 871.2724874729608, 499.22525422124335, 514.0823629820752, 614.0420943328378, 647.2621211818531, 647.9147428057984, 12160.148789584733, 12416.56542021034, 12421.75156496865, 12422.689432764162, 12429.37853107341, 10197.89802101977, 10399.532021058994, 10446.68659940203, 10571.101456376216, 10709.196810239186, 9662.3719458235, 9699.127078562893, 9751.707448776548, 9800.55897484615, 10054.9497252514, 2991.1176203020163, 3040.6047213862275, 3044.6955304469375, 3081.337512434815, 3103.5810165627577, 96055.0, 97452.025479745, 100152.49923750407, 100291.49854250756, 100465.0, 7805.80485487856, 7964.141036002725, 8176.836463270702, 8264.049634292134, 8418.242358187792, 16555.66888662244, 16722.0, 16744.0, 16854.325826966782, 17080.48758537246, 7161.534500257467, 7279.708811647475, 7434.405247580181, 7611.809704757426, 7631.313181813553, 4052.250333688277, 4146.232946904832, 4159.334506478949, 4209.284421648266, 4243.978780106079, 3230.2731885326084, 3327.1349449143395, 3349.129226401153, 3357.3788849063003, 3365.4447016242048, 562.6624025584616, 628.5914155798768, 628.7547856336032, 635.939585739354, 639.6993413095837, 576.5502907731976, 580.8576898659771, 594.6135012242078, 631.2456614345905, 640.9647469389135, 7926.326262267657, 8279.172082791792, 8426.368022398345, 8543.429265343011, 8625.568721564023, 549.3353042818235, 556.3849905444428, 600.5135839969638, 614.4255121461448, 629.6064959400375, 4073.5315438724333, 4188.099558594917, 4189.49726032878, 4191.97904010478, 4341.522432532419, 70051.64974175097, 70144.94782578266, 70391.0, 71071.0978393818, 84507.57746211352, 94311.52844235735, 96058.55912161323, 96314.51842740741, 98183.0, 98713.0, 81542.0, 84877.30245395057, 85169.57415212884, 85555.5722221385, 86624.0, 7069.222385537484, 8234.300084492885, 8250.504969701837, 8376.83246335082, 8461.703840365557, 48758.75620621874, 51199.48800511947, 51395.22907156321, 54043.0, 54472.18291725548, 59583.0, 69109.65445172942, 76074.85887711578, 76704.23295766971, 77128.0, 11146.0, 11286.49210785533, 11329.206955513364, 11593.130515211224, 11713.414329283672, 12104.697382565506, 12274.938625306817, 12280.570180043656, 12405.875941240472, 12810.359482026048, 11513.424328783694, 11600.477978491157, 11710.648680539598, 11868.287902725864, 11868.584599538975, 11680.94159529197, 11692.415379231175, 11755.823662644896, 11871.287722736663, 12218.816717749418, 11405.372704501, 11524.250923689933, 11656.18406711522, 11692.060866852577, 12156.635300940987, 9357.251419886528, 9537.284703647392, 10014.0, 10017.098461138532, 10116.342437741523, 8306.418550701635, 8560.871586926076, 9864.0, 9887.703368898945, 9891.653792117242], "value": 6696.39106669992, "unit": "score"}], "name": "dromaeo_css", "value": 6811.360916920061, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 114.0, 115.0, 116.0, 114.0, 118.0, 161.0, 152.0], "value": 123.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 134.0, 136.0, 148.0, 114.0, 113.0, 111.0, 111.0, 149.0, 138.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 187.0, 183.0, 185.0, 183.0, 183.0, 179.0, 180.0, 183.0, 183.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 50.0, 58.0, 107.0, 84.0, 81.0, 59.0, 58.0, 84.0, 58.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 92.0, 70.0, 69.0, 69.0, 90.0, 68.0, 69.0, 92.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 87.0, 90.0, 86.0, 90.0, 88.0, 89.0, 86.0], "value": 88.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 108.0, 145.0, 146.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 106.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 82.0, 86.0, 85.0, 88.0, 87.0, 86.0, 87.0, 87.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 53.0, 55.0, 55.0, 54.0, 56.0, 55.0, 55.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 81.0, 74.0, 72.0, 93.0, 98.0, 98.0, 83.0, 70.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 87.0, 89.0, 89.0, 87.0, 88.0, 84.0, 87.0, 86.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 149.0, 146.0, 150.0, 138.0, 154.0, 150.0, 149.0, 189.0, 152.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 230.0, 195.0, 173.0, 174.0, 176.0, 173.0, 225.0, 221.0, 176.0], "value": 192.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 67.0, 68.0, 66.0, 68.0, 66.0, 67.0, 66.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1502.5000000000002, "alertThreshold": 2.0}]} 23:14:16 INFO - Return code: 0 23:14:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:14:16 INFO - # TBPL SUCCESS # 23:14:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:14:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:14:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:14:16 INFO - 2016-11-16 23:14:16.152 screenresolution[1650:9653] starting screenresolution argv=screenresolution get 23:14:16 INFO - 2016-11-16 23:14:16.154 screenresolution[1650:9653] Display 0: 1600x1200x32@60 23:14:16 INFO - 2016-11-16 23:14:16.160 screenresolution[1651:9656] starting screenresolution argv=screenresolution list 23:14:16 INFO - Available Modes on Display 0 23:14:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:14:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:14:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:14:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:14:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:14:16 INFO - Intel Iris: 23:14:16 INFO - Chipset Model: Intel Iris 23:14:16 INFO - Type: GPU 23:14:16 INFO - Bus: Built-In 23:14:16 INFO - VRAM (Dynamic, Max): 1536 MB 23:14:16 INFO - Vendor: Intel (0x8086) 23:14:16 INFO - Device ID: 0x0a2e 23:14:16 INFO - Revision ID: 0x0009 23:14:16 INFO - Displays: 23:14:16 INFO - Display: 23:14:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:14:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:14:16 INFO - Display Serial Number: 1600x1200 60 23:14:16 INFO - Main Display: Yes 23:14:16 INFO - Mirror: Off 23:14:16 INFO - Online: Yes 23:14:16 INFO - Rotation: Supported 23:14:16 INFO - Return code: 0 23:14:16 INFO - Running post-action listener: _resource_record_post_action 23:14:16 INFO - [mozharness: 2016-11-17 07:14:16.397486Z] Finished run-tests step (success) 23:14:16 INFO - Running post-run listener: _resource_record_post_run 23:14:16 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 37520384; Write bytes: 701087232; Read time: 241; Write time: 1976 23:14:16 INFO - TinderboxPrint: CPU usage
53.8% 23:14:16 INFO - TinderboxPrint: I/O read bytes / time
37,520,384 / 241 23:14:16 INFO - TinderboxPrint: I/O write bytes / time
701,087,232 / 1,976 23:14:16 INFO - TinderboxPrint: CPU idle
2,245.7 (46.2%) 23:14:16 INFO - TinderboxPrint: CPU system
970.8 (20.0%) 23:14:16 INFO - TinderboxPrint: CPU user
1,645.1 (33.8%) 23:14:16 INFO - TinderboxPrint: Swap in / out
435,904,512 / 0 23:14:16 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 387371520; Write bytes: 381630464; Read time: 29531; Write time: 820 23:14:16 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 35701760; Write bytes: 299156992; Read time: 224; Write time: 1099 23:14:16 INFO - Running post-run listener: _upload_blobber_files 23:14:16 INFO - Blob upload gear active. 23:14:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:14:16 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 23:14:16 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:14:16 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:14:17 INFO - (blobuploader) - INFO - Open directory for files ... 23:14:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:14:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:14:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:14:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:14:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:14:18 INFO - (blobuploader) - INFO - Done attempting. 23:14:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:14:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:14:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:14:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:14:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:14:19 INFO - (blobuploader) - INFO - Done attempting. 23:14:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:14:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:14:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:14:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:14:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:14:21 INFO - (blobuploader) - INFO - Done attempting. 23:14:21 INFO - (blobuploader) - INFO - Iteration through files over. 23:14:21 INFO - Return code: 0 23:14:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:14:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:14:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33c772865bc644b1cae47a38ae882746affa30bdb90b7a008c34c4765ce0b81d61b2c6aea1c37d8344687452ce2a9d092b83a52cd593f9b7cc8dca19ff2f3152", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9031ec01740780052cda307f5e19ff28e81eb96118b27271fb79a1d42a34b179b9d0bdb40b0cddaa083f3f5a4023aace64acfdf5e36f3c8a28d927bbfaf96899", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6a84d35a2c0279c1ef036e36f60a92a2cc832f4a94832aa52dda7f1951b3cc5cd232ec9e140452fdba7aa82b00ba98ed74218b130f468e709f28935f2aa2cb7"} 23:14:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:14:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:14:21 INFO - Contents: 23:14:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33c772865bc644b1cae47a38ae882746affa30bdb90b7a008c34c4765ce0b81d61b2c6aea1c37d8344687452ce2a9d092b83a52cd593f9b7cc8dca19ff2f3152", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9031ec01740780052cda307f5e19ff28e81eb96118b27271fb79a1d42a34b179b9d0bdb40b0cddaa083f3f5a4023aace64acfdf5e36f3c8a28d927bbfaf96899", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6a84d35a2c0279c1ef036e36f60a92a2cc832f4a94832aa52dda7f1951b3cc5cd232ec9e140452fdba7aa82b00ba98ed74218b130f468e709f28935f2aa2cb7"} 23:14:21 INFO - Running post-run listener: copy_logs_to_upload_dir 23:14:21 INFO - Copying logs to upload dir... 23:14:21 INFO - mkdir: /builds/slave/test/build/upload/logs 23:14:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.250132 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-16 23:14:21.523279) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 23:14:21.530542) ========= 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.ROIdWbZE9H/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.46fb5GinWZ/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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/Firefox/sha512/33c772865bc644b1cae47a38ae882746affa30bdb90b7a008c34c4765ce0b81d61b2c6aea1c37d8344687452ce2a9d092b83a52cd593f9b7cc8dca19ff2f3152", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9031ec01740780052cda307f5e19ff28e81eb96118b27271fb79a1d42a34b179b9d0bdb40b0cddaa083f3f5a4023aace64acfdf5e36f3c8a28d927bbfaf96899", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6a84d35a2c0279c1ef036e36f60a92a2cc832f4a94832aa52dda7f1951b3cc5cd232ec9e140452fdba7aa82b00ba98ed74218b130f468e709f28935f2aa2cb7"} build_url:https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011429 build_url: 'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33c772865bc644b1cae47a38ae882746affa30bdb90b7a008c34c4765ce0b81d61b2c6aea1c37d8344687452ce2a9d092b83a52cd593f9b7cc8dca19ff2f3152", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9031ec01740780052cda307f5e19ff28e81eb96118b27271fb79a1d42a34b179b9d0bdb40b0cddaa083f3f5a4023aace64acfdf5e36f3c8a28d927bbfaf96899", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6a84d35a2c0279c1ef036e36f60a92a2cc832f4a94832aa52dda7f1951b3cc5cd232ec9e140452fdba7aa82b00ba98ed74218b130f468e709f28935f2aa2cb7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 23:14:21.566583) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 23:14:21.566879) ========= 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.ROIdWbZE9H/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.46fb5GinWZ/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.004995 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 23:14:21.614818) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 23:14:21.615126) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 23:14:21.617451) ========= ========= Total master_lag: 0.19 =========