builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0363 starttime: 1481365647.33 results: success (0) buildid: 20161210004004 builduid: b33c388b49f14b2abdba5b5f0cbbfe4b revision: 0d52c1ee6bce8b6b8d0b1774ce4b15082e6b4854 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-10 02:27:27.329963) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-10 02:27:27.330361) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-10 02:27:27.330637) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-10 02:27:27.556618) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 02:27:27.556901) ========= 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.71xVysPSSV/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.pS7D4pEdhE/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-10 02:27:27-- 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% 19.6M=0.001s 2016-12-10 02:27:28 (19.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.580504 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 02:27:28.162397) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 02:27:28.162712) ========= 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.71xVysPSSV/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.pS7D4pEdhE/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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.024546 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 02:27:28.211129) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-10 02:27:28.211455) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0d52c1ee6bce8b6b8d0b1774ce4b15082e6b4854 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0d52c1ee6bce8b6b8d0b1774ce4b15082e6b4854 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.71xVysPSSV/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.pS7D4pEdhE/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-10 02:27:28,310 truncating revision to first 12 chars 2016-12-10 02:27:28,310 Setting DEBUG logging. 2016-12-10 02:27:28,310 attempt 1/10 2016-12-10 02:27:28,311 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0d52c1ee6bce?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-10 02:27:29,420 unpacking tar archive at: mozilla-aurora-0d52c1ee6bce/testing/mozharness/ program finished with exit code 0 elapsedTime=1.392731 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-10 02:27:29.627686) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-10 02:27:29.628070) ========= script_repo_revision: 0d52c1ee6bce8b6b8d0b1774ce4b15082e6b4854 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-10 02:27:29.628476) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-10 02:27:29.628758) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-10 02:27:29.648318) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-12-10 02:27:29.648683) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.71xVysPSSV/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.pS7D4pEdhE/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 02:27:29 INFO - MultiFileLogger online at 20161210 02:27:29 in /builds/slave/test 02:27:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:27:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:27:29 INFO - {'append_to_log': False, 02:27:29 INFO - 'base_work_dir': '/builds/slave/test', 02:27:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:27:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:27:29 INFO - 'branch': 'Mozilla-Aurora', 02:27:29 INFO - 'buildbot_json_path': 'buildprops.json', 02:27:29 INFO - 'config_files': ('talos/mac_config.py',), 02:27:29 INFO - 'default_actions': ('clobber', 02:27:29 INFO - 'read-buildbot-config', 02:27:29 INFO - 'download-and-extract', 02:27:29 INFO - 'populate-webroot', 02:27:29 INFO - 'create-virtualenv', 02:27:29 INFO - 'install', 02:27:29 INFO - 'run-tests'), 02:27:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:27:29 INFO - 'download_minidump_stackwalk': True, 02:27:29 INFO - 'download_symbols': 'ondemand', 02:27:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:27:29 INFO - 'tooltool.py': '/tools/tooltool.py', 02:27:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:27:29 INFO - '/tools/misc-python/virtualenv.py')}, 02:27:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:27:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:27:29 INFO - 'installer_path': 'installer.exe', 02:27:29 INFO - 'log_level': 'info', 02:27:29 INFO - 'log_name': 'talos', 02:27:29 INFO - 'log_to_console': True, 02:27:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:27:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:27:29 INFO - 'opt_config_files': (), 02:27:29 INFO - 'pip_index': False, 02:27:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:29 INFO - 'cmd': ('bash', 02:27:29 INFO - '-c', 02:27:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:29 INFO - 'enabled': True, 02:27:29 INFO - 'halt_on_failure': False, 02:27:29 INFO - 'name': 'check_screen_resolution'},), 02:27:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:29 INFO - 'cmd': ('bash', 02:27:29 INFO - '-c', 02:27:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:29 INFO - 'enabled': True, 02:27:29 INFO - 'halt_on_failure': False, 02:27:29 INFO - 'name': 'check_screen_resolution'},), 02:27:29 INFO - 'run_cmd_checks_enabled': True, 02:27:29 INFO - 'sps_profile': False, 02:27:29 INFO - 'sps_profile_interval': 0, 02:27:29 INFO - 'suite': 'dromaeojs', 02:27:29 INFO - 'system_bits': '32', 02:27:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:27:29 INFO - 'title': 't-yosemite-r7-0363', 02:27:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:27:29 INFO - 'use_talos_json': True, 02:27:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:27:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:27:29 INFO - 'work_dir': 'build'} 02:27:29 INFO - [mozharness: 2016-12-10 10:27:29.856787Z] Running clobber step. 02:27:29 INFO - Running pre-action listener: _resource_record_pre_action 02:27:29 INFO - Running main action method: clobber 02:27:29 INFO - rmtree: /builds/slave/test/build 02:27:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:27:32 INFO - Running post-action listener: _resource_record_post_action 02:27:32 INFO - [mozharness: 2016-12-10 10:27:32.228834Z] Finished clobber step (success) 02:27:32 INFO - [mozharness: 2016-12-10 10:27:32.229029Z] Running read-buildbot-config step. 02:27:32 INFO - Running pre-action listener: _resource_record_pre_action 02:27:32 INFO - Running main action method: read_buildbot_config 02:27:32 INFO - Using buildbot properties: 02:27:32 INFO - { 02:27:32 INFO - "project": "", 02:27:32 INFO - "product": "firefox", 02:27:32 INFO - "script_repo_revision": "production", 02:27:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:27:32 INFO - "repository": "", 02:27:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:27:32 INFO - "buildid": "20161210004004", 02:27:32 INFO - "stage_platform": "macosx64", 02:27:32 INFO - "basedir": "/builds/slave/test", 02:27:32 INFO - "nightly_build": "True", 02:27:32 INFO - "buildnumber": 8, 02:27:32 INFO - "slavename": "t-yosemite-r7-0363", 02:27:32 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:27:32 INFO - "platform": "yosemite_r7", 02:27:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:27:32 INFO - "branch": "mozilla-aurora", 02:27:32 INFO - "repo_path": "releases/mozilla-aurora", 02:27:32 INFO - "revision": "0d52c1ee6bce8b6b8d0b1774ce4b15082e6b4854", 02:27:32 INFO - "pgo_build": "False", 02:27:32 INFO - "builduid": "b33c388b49f14b2abdba5b5f0cbbfe4b", 02:27:32 INFO - "slavebuilddir": "test" 02:27:32 INFO - } 02:27:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:27:32 INFO - Running post-action listener: _resource_record_post_action 02:27:32 INFO - [mozharness: 2016-12-10 10:27:32.234349Z] Finished read-buildbot-config step (success) 02:27:32 INFO - [mozharness: 2016-12-10 10:27:32.234563Z] Running download-and-extract step. 02:27:32 INFO - Running pre-action listener: _resource_record_pre_action 02:27:32 INFO - Running main action method: download_and_extract 02:27:32 INFO - mkdir: /builds/slave/test/build/tests 02:27: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')]} 02:27:32 INFO - trying https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:27:32 INFO - Downloading https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:27:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:27:33 INFO - Downloaded 1199 bytes. 02:27:33 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:27:33 INFO - Using the following test package requirements: 02:27:33 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:27:33 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:33 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:27:33 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:33 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:27:33 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:33 INFO - u'jsshell-mac.zip'], 02:27:33 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:33 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:27:33 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:27:33 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:33 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:27:33 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:33 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:27:33 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:33 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:27:33 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:33 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:27:33 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:27:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:27:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:27:33 INFO - Fetch https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:27:34 INFO - Http code: 200 02:27:34 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 02:27:34 INFO - x-amz-cf-id: ncTsFdrDbTWZjj2zaQTLyn_GOsNxpLZdL-IizpK4D9jW9M1aoUNdWQ== 02:27:34 INFO - x-amz-version-id: Qhgolqy4bQUCgDrblDPQfGB5Bp3.9Tnp 02:27:34 INFO - Expected file size: 16281035 02:27:34 INFO - Obtained file size: 16281035 02:27:41 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:27:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:27:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:27:41 INFO - Fetch https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:27:42 INFO - Http code: 200 02:27:42 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 02:27:42 INFO - x-amz-cf-id: dE1rys3LlSnXDefl1iiQy70WhnFhwV0F6DhzFGHXIoQ6nStr8SKFpg== 02:27:42 INFO - x-amz-version-id: Qhgolqy4bQUCgDrblDPQfGB5Bp3.9Tnp 02:27:42 INFO - Expected file size: 16281035 02:27:42 INFO - Obtained file size: 16281035 02:27:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:27:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:27:48 INFO - Fetch https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:27:49 INFO - Http code: 200 02:27:49 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 02:27:49 INFO - x-amz-cf-id: LdXd6LF0bNvaWD6axxdEAHbTvug93T5mluYB9sLgm3mimBQARTxKoA== 02:27:49 INFO - x-amz-version-id: A8Hf8DE_NlKkTRx6MlOgtGtRyr9sJ0Lh 02:27:49 INFO - Expected file size: 14409038 02:27:49 INFO - Obtained file size: 14409038 02:27: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')]} 02:27:49 INFO - trying https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:27:49 INFO - Downloading https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:27:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 02:27:52 INFO - Downloaded 109855472 bytes. 02:27:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:27:52 INFO - mkdir: /builds/slave/test/properties 02:27:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:27:52 INFO - Writing to file /builds/slave/test/properties/build_url 02:27:52 INFO - Contents: 02:27:52 INFO - build_url:https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:27:53 INFO - Running post-action listener: _resource_record_post_action 02:27:53 INFO - Running post-action listener: set_extra_try_arguments 02:27:53 INFO - [mozharness: 2016-12-10 10:27:53.574881Z] Finished download-and-extract step (success) 02:27:53 INFO - [mozharness: 2016-12-10 10:27:53.575002Z] Running populate-webroot step. 02:27:53 INFO - Running pre-action listener: _resource_record_pre_action 02:27:53 INFO - Running main action method: populate_webroot 02:27:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:27:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:27:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:27:53 INFO - '--tppagecycles', 02:27:53 INFO - '7'], 02:27:53 INFO - 'tests': ['tsvgm']}}, 02:27:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:27:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:27:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:27:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:27:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:27:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:27:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:53 INFO - 'talos_options': ['--disable-e10s'], 02:27:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:27:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:27:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:53 INFO - 'talos_options': ['--disable-e10s'], 02:27:53 INFO - 'tests': ['damp', 'tps']}, 02:27:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:53 INFO - 'tests': ['damp', 'tps']}, 02:27:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:27:53 INFO - 'tests': ['dromaeo_dom']}, 02:27:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:27:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:27:53 INFO - 'tests': ['basic_compositor_video']}, 02:27:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:27:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:27:53 INFO - 'tests': ['a11yr', 02:27:53 INFO - 'ts_paint', 02:27:53 INFO - 'tpaint', 02:27:53 INFO - 'sessionrestore', 02:27:53 INFO - 'sessionrestore_no_auto_restore', 02:27:53 INFO - 'tabpaint']}, 02:27:53 INFO - 'other-e10s': {'tests': ['a11yr', 02:27:53 INFO - 'ts_paint', 02:27:53 INFO - 'tpaint', 02:27:53 INFO - 'sessionrestore', 02:27:53 INFO - 'sessionrestore_no_auto_restore', 02:27:53 INFO - 'tabpaint']}, 02:27:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:27:53 INFO - 'ts_paint', 02:27:53 INFO - 'tpaint', 02:27:53 INFO - 'sessionrestore', 02:27:53 INFO - 'sessionrestore_no_auto_restore', 02:27:53 INFO - 'tabpaint']}, 02:27:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:27:53 INFO - 'ts_paint', 02:27:53 INFO - 'tpaint', 02:27:53 INFO - 'sessionrestore', 02:27:53 INFO - 'sessionrestore_no_auto_restore', 02:27:53 INFO - 'tabpaint']}, 02:27:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:27:53 INFO - 'tests': ['a11yr', 02:27:53 INFO - 'ts_paint', 02:27:53 INFO - 'tpaint', 02:27:53 INFO - 'sessionrestore', 02:27:53 INFO - 'sessionrestore_no_auto_restore', 02:27:53 INFO - 'tabpaint']}, 02:27:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:27:53 INFO - 'tests': ['a11yr', 02:27:53 INFO - 'ts_paint', 02:27:53 INFO - 'tpaint', 02:27:53 INFO - 'sessionrestore', 02:27:53 INFO - 'sessionrestore_no_auto_restore', 02:27:53 INFO - 'tabpaint']}, 02:27:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:27:53 INFO - 'tests': ['tsvgx', 02:27:53 INFO - 'tsvgr_opacity', 02:27:53 INFO - 'tart', 02:27:53 INFO - 'tscrollx', 02:27:53 INFO - 'cart']}, 02:27:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:27:53 INFO - 'tsvgr_opacity', 02:27:53 INFO - 'tart', 02:27:53 INFO - 'tscrollx', 02:27:53 INFO - 'cart']}, 02:27:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:53 INFO - 'talos_options': ['--disable-e10s'], 02:27:53 INFO - 'tests': ['tp5o']}, 02:27:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:53 INFO - 'tests': ['tp5o']}, 02:27:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:53 INFO - 'talos_options': ['--disable-e10s', 02:27:53 INFO - '--xperf_path', 02:27:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:27:53 INFO - 'tests': ['tp5n']}, 02:27:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:53 INFO - 'talos_options': ['--xperf_path', 02:27:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:27:53 INFO - 'tests': ['tp5n']}}, 02:27:53 INFO - 'talos.zip': {'path': '', 02:27:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:27:53 INFO - Running post-action listener: _resource_record_post_action 02:27:53 INFO - [mozharness: 2016-12-10 10:27:53.593743Z] Finished populate-webroot step (success) 02:27:53 INFO - [mozharness: 2016-12-10 10:27:53.593853Z] Running create-virtualenv step. 02:27:53 INFO - Running pre-action listener: _resource_record_pre_action 02:27:53 INFO - Running main action method: create_virtualenv 02:27:53 INFO - Creating virtualenv /builds/slave/test/build/venv 02:27:53 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 02:27:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:27:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:27:53 INFO - Using real prefix '/tools/python27' 02:27:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:27:54 INFO - Installing distribute.............................................................................................................................................................................................done. 02:27:57 INFO - Installing pip.................done. 02:27:57 INFO - Return code: 0 02:27:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:27:57 INFO - Reading from file tmpfile_stdout 02:27:57 INFO - Output received: 02:27:57 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) 02:27:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10747a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa025a6840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10728ed30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107286b20>, '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.71xVysPSSV/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.pS7D4pEdhE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 02:27:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:27:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.71xVysPSSV/Render', 02:27:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:57 INFO - 'HOME': '/Users/cltbld', 02:27:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:57 INFO - 'LOGNAME': 'cltbld', 02:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:57 INFO - 'MOZ_NO_REMOTE': '1', 02:27:57 INFO - 'NO_EM_RESTART': '1', 02:27:57 INFO - 'PAGER': '/bin/cat', 02:27:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:57 INFO - 'PWD': '/builds/slave/test', 02:27:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:57 INFO - 'SHELL': '/bin/bash', 02:27:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pS7D4pEdhE/Listeners', 02:27:57 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 02:27:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:57 INFO - 'USER': 'cltbld', 02:27:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:57 INFO - 'XPC_FLAGS': '0x0', 02:27:57 INFO - 'XPC_SERVICE_NAME': '0', 02:27:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:57 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 02:27:57 INFO - Cleaning up... 02:27:57 INFO - Return code: 0 02:27:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10747a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa025a6840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10728ed30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107286b20>, '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.71xVysPSSV/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.pS7D4pEdhE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 02:27:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:27:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.71xVysPSSV/Render', 02:27:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:57 INFO - 'HOME': '/Users/cltbld', 02:27:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:57 INFO - 'LOGNAME': 'cltbld', 02:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:57 INFO - 'MOZ_NO_REMOTE': '1', 02:27:57 INFO - 'NO_EM_RESTART': '1', 02:27:57 INFO - 'PAGER': '/bin/cat', 02:27:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:57 INFO - 'PWD': '/builds/slave/test', 02:27:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:57 INFO - 'SHELL': '/bin/bash', 02:27:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pS7D4pEdhE/Listeners', 02:27:57 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 02:27:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:57 INFO - 'USER': 'cltbld', 02:27:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:57 INFO - 'XPC_FLAGS': '0x0', 02:27:57 INFO - 'XPC_SERVICE_NAME': '0', 02:27:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:58 INFO - Downloading/unpacking psutil>=3.1.1 02:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:28:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:01 INFO - Installing collected packages: psutil 02:28:01 INFO - Running setup.py install for psutil 02:28:01 INFO - building 'psutil._psutil_osx' extension 02:28:01 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 02:28:02 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 02:28:02 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 02:28:02 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 02:28:02 INFO - building 'psutil._psutil_posix' extension 02:28:02 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 02:28:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:28:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:28:02 INFO - ^ 02:28:02 INFO - 1 warning generated. 02:28:02 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 02:28:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:02 INFO - Successfully installed psutil 02:28:02 INFO - Cleaning up... 02:28:03 INFO - Return code: 0 02:28:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:03 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 0x10747a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa025a6840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10728ed30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107286b20>, '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.71xVysPSSV/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.pS7D4pEdhE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 02:28:03 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 02:28:03 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 02:28:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.71xVysPSSV/Render', 02:28:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:03 INFO - 'HOME': '/Users/cltbld', 02:28:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:03 INFO - 'LOGNAME': 'cltbld', 02:28:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:03 INFO - 'MOZ_NO_REMOTE': '1', 02:28:03 INFO - 'NO_EM_RESTART': '1', 02:28:03 INFO - 'PAGER': '/bin/cat', 02:28:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:03 INFO - 'PWD': '/builds/slave/test', 02:28:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:03 INFO - 'SHELL': '/bin/bash', 02:28:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pS7D4pEdhE/Listeners', 02:28:03 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 02:28:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:03 INFO - 'USER': 'cltbld', 02:28:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:03 INFO - 'XPC_FLAGS': '0x0', 02:28:03 INFO - 'XPC_SERVICE_NAME': '0', 02:28:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:28:03 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 02:28:03 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 02:28:03 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 02:28:03 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 02:28:03 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 02:28:03 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 02:28:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:28:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:28:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:28:05 INFO - Installing collected packages: mozsystemmonitor 02:28:05 INFO - Running setup.py install for mozsystemmonitor 02:28:06 INFO - Successfully installed mozsystemmonitor 02:28:06 INFO - Cleaning up... 02:28:06 INFO - Return code: 0 02:28:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:28: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')]} 02:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28: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')]} 02:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10747a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa025a6840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10728ed30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107286b20>, '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.71xVysPSSV/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.pS7D4pEdhE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 02:28:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:28:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.71xVysPSSV/Render', 02:28:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:06 INFO - 'HOME': '/Users/cltbld', 02:28:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:06 INFO - 'LOGNAME': 'cltbld', 02:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:06 INFO - 'MOZ_NO_REMOTE': '1', 02:28:06 INFO - 'NO_EM_RESTART': '1', 02:28:06 INFO - 'PAGER': '/bin/cat', 02:28:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:06 INFO - 'PWD': '/builds/slave/test', 02:28:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:06 INFO - 'SHELL': '/bin/bash', 02:28:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pS7D4pEdhE/Listeners', 02:28:06 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 02:28:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:06 INFO - 'USER': 'cltbld', 02:28:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:06 INFO - 'XPC_FLAGS': '0x0', 02:28:06 INFO - 'XPC_SERVICE_NAME': '0', 02:28:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:06 INFO - Downloading/unpacking jsonschema==2.5.1 02:28:06 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 02:28:06 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 02:28:06 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 02:28:06 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 02:28:06 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 02:28:06 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 02:28:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:28:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:09 INFO - Downloading functools32-3.2.3-2.tar.gz 02:28:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:28:09 INFO - warning: no files found matching '*.txt' 02:28:09 INFO - no previously-included directories found matching 'build' 02:28:09 INFO - no previously-included directories found matching 'dist' 02:28:09 INFO - no previously-included directories found matching '.git*' 02:28:09 INFO - Installing collected packages: jsonschema, functools32 02:28:09 INFO - Running setup.py install for functools32 02:28:09 INFO - warning: no files found matching '*.txt' 02:28:09 INFO - no previously-included directories found matching 'build' 02:28:09 INFO - no previously-included directories found matching 'dist' 02:28:09 INFO - no previously-included directories found matching '.git*' 02:28:09 INFO - Successfully installed jsonschema functools32 02:28:09 INFO - Cleaning up... 02:28:09 INFO - Return code: 0 02:28:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:28:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:09 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 0x10747a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa025a6840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10728ed30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107286b20>, '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.71xVysPSSV/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.pS7D4pEdhE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 02:28:09 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 02:28:09 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 02:28:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.71xVysPSSV/Render', 02:28:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:09 INFO - 'HOME': '/Users/cltbld', 02:28:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:09 INFO - 'LOGNAME': 'cltbld', 02:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:09 INFO - 'MOZ_NO_REMOTE': '1', 02:28:09 INFO - 'NO_EM_RESTART': '1', 02:28:09 INFO - 'PAGER': '/bin/cat', 02:28:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:09 INFO - 'PWD': '/builds/slave/test', 02:28:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:09 INFO - 'SHELL': '/bin/bash', 02:28:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pS7D4pEdhE/Listeners', 02:28:09 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 02:28:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:09 INFO - 'USER': 'cltbld', 02:28:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:09 INFO - 'XPC_FLAGS': '0x0', 02:28:09 INFO - 'XPC_SERVICE_NAME': '0', 02:28:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:28:10 INFO - Cleaning up... 02:28:10 INFO - Return code: 0 02:28:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:28:10 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')]} 02:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:10 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')]} 02:28:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:10 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 0x10747a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa025a6840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10728ed30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107286b20>, '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.71xVysPSSV/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.pS7D4pEdhE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 02:28:10 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 02:28:10 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 02:28:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.71xVysPSSV/Render', 02:28:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:10 INFO - 'HOME': '/Users/cltbld', 02:28:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:10 INFO - 'LOGNAME': 'cltbld', 02:28:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:10 INFO - 'MOZ_NO_REMOTE': '1', 02:28:10 INFO - 'NO_EM_RESTART': '1', 02:28:10 INFO - 'PAGER': '/bin/cat', 02:28:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:10 INFO - 'PWD': '/builds/slave/test', 02:28:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:10 INFO - 'SHELL': '/bin/bash', 02:28:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pS7D4pEdhE/Listeners', 02:28:10 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 02:28:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:10 INFO - 'USER': 'cltbld', 02:28:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:10 INFO - 'XPC_FLAGS': '0x0', 02:28:10 INFO - 'XPC_SERVICE_NAME': '0', 02:28:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:10 INFO - Downloading/unpacking blobuploader==1.2.4 02:28:10 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 02:28:10 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 02:28:10 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 02:28:10 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 02:28:10 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 02:28:10 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 02:28:12 INFO - Downloading blobuploader-1.2.4.tar.gz 02:28:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:28:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:28:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:28:13 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 02:28:13 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 02:28:13 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 02:28:13 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 02:28:13 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 02:28:13 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 02:28:14 INFO - Downloading docopt-0.6.1.tar.gz 02:28:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:28:14 INFO - Installing collected packages: blobuploader, requests, docopt 02:28:14 INFO - Running setup.py install for blobuploader 02:28:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:28:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:28:14 INFO - Running setup.py install for requests 02:28:14 INFO - Running setup.py install for docopt 02:28:14 INFO - Successfully installed blobuploader requests docopt 02:28:14 INFO - Cleaning up... 02:28:15 INFO - Return code: 0 02:28:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:15 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 0x10747a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa025a6840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10728ed30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107286b20>, '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.71xVysPSSV/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.pS7D4pEdhE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 02:28:15 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 02:28:15 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 02:28:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.71xVysPSSV/Render', 02:28:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:15 INFO - 'HOME': '/Users/cltbld', 02:28:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:15 INFO - 'LOGNAME': 'cltbld', 02:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:15 INFO - 'MOZ_NO_REMOTE': '1', 02:28:15 INFO - 'NO_EM_RESTART': '1', 02:28:15 INFO - 'PAGER': '/bin/cat', 02:28:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:15 INFO - 'PWD': '/builds/slave/test', 02:28:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:15 INFO - 'SHELL': '/bin/bash', 02:28:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pS7D4pEdhE/Listeners', 02:28:15 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 02:28:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:15 INFO - 'USER': 'cltbld', 02:28:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:15 INFO - 'XPC_FLAGS': '0x0', 02:28:15 INFO - 'XPC_SERVICE_NAME': '0', 02:28:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:15 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-fqJd89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:15 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-jbKdZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:15 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-ytECHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:15 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-w1Ki9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:15 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-iSPZq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:15 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-Zd_e33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:15 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-X1HW72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:16 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-LIOgbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:16 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-o6gj0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:16 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-ff4wPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:16 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-5XYkcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:16 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-OkG8eb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:16 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-UdG_Ig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:16 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-VNBDpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:16 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-E8AMnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:16 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-2zYpdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:17 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-RR8TkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:28:17 INFO - Running setup.py install for manifestparser 02:28:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:28:17 INFO - Running setup.py install for mozcrash 02:28:17 INFO - Running setup.py install for mozdebug 02:28:17 INFO - Running setup.py install for mozdevice 02:28:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:28:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:28:17 INFO - Running setup.py install for mozfile 02:28:17 INFO - Running setup.py install for mozhttpd 02:28:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:28:18 INFO - Running setup.py install for mozinfo 02:28:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:28:18 INFO - Running setup.py install for mozInstall 02:28:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:28:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:28:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:28:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:28:18 INFO - Running setup.py install for mozleak 02:28:18 INFO - Running setup.py install for mozlog 02:28:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:28:18 INFO - Running setup.py install for moznetwork 02:28:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:28:18 INFO - Running setup.py install for mozprocess 02:28:18 INFO - Running setup.py install for mozprofile 02:28:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:28:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:28:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:28:19 INFO - Running setup.py install for mozrunner 02:28:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:28:19 INFO - Running setup.py install for mozscreenshot 02:28:19 INFO - Running setup.py install for moztest 02:28:19 INFO - Running setup.py install for mozversion 02:28:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:28:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:28:19 INFO - Cleaning up... 02:28:19 INFO - Return code: 0 02:28:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:19 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')]} 02:28:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:19 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')]} 02:28:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:19 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 0x10747a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa025a6840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10728ed30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107286b20>, '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.71xVysPSSV/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.pS7D4pEdhE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 02:28:19 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 02:28:19 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 02:28:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.71xVysPSSV/Render', 02:28:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:19 INFO - 'HOME': '/Users/cltbld', 02:28:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:19 INFO - 'LOGNAME': 'cltbld', 02:28:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:19 INFO - 'MOZ_NO_REMOTE': '1', 02:28:19 INFO - 'NO_EM_RESTART': '1', 02:28:19 INFO - 'PAGER': '/bin/cat', 02:28:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:19 INFO - 'PWD': '/builds/slave/test', 02:28:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:19 INFO - 'SHELL': '/bin/bash', 02:28:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pS7D4pEdhE/Listeners', 02:28:19 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 02:28:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:19 INFO - 'USER': 'cltbld', 02:28:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:19 INFO - 'XPC_FLAGS': '0x0', 02:28:19 INFO - 'XPC_SERVICE_NAME': '0', 02:28:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:20 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-CUYn6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:20 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)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:20 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-TnHmX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:20 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)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:20 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-2tJDnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:20 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)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:20 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-5u2YIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:20 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)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:20 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-7A2w8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:20 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)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:20 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-TFCtmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:20 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)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:20 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-P4lBYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:20 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)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:20 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-N8KZVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:20 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)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:20 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-LTdLpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:20 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)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:20 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-1vERp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:21 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)) 02:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:21 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-OVd3uS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:21 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)) 02:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:21 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-fDF5kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:21 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)) 02:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:21 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-n19hQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:21 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)) 02:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:21 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-5S8NzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:21 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)) 02:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:21 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-ye1Gki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:21 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)) 02:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:21 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-MWXGSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:21 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)) 02:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:21 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-rcPxv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:21 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)) 02:28:21 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)) 02:28:21 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)) 02:28:21 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)) 02:28:21 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)) 02:28:21 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)) 02:28:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:28:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:24 INFO - Downloading blessings-1.6.tar.gz 02:28:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:28:24 INFO - Installing collected packages: blessings 02:28:24 INFO - Running setup.py install for blessings 02:28:24 INFO - Successfully installed blessings 02:28:24 INFO - Cleaning up... 02:28:24 INFO - Return code: 0 02:28:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:28:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:25 INFO - Reading from file tmpfile_stdout 02:28:25 INFO - Current package versions: 02:28:25 INFO - blessings == 1.6 02:28:25 INFO - blobuploader == 1.2.4 02:28:25 INFO - docopt == 0.6.1 02:28:25 INFO - functools32 == 3.2.3-2 02:28:25 INFO - jsonschema == 2.5.1 02:28:25 INFO - manifestparser == 1.1 02:28:25 INFO - mozInstall == 1.12 02:28:25 INFO - mozcrash == 1.0 02:28:25 INFO - mozdebug == 0.1 02:28:25 INFO - mozdevice == 0.48 02:28:25 INFO - mozfile == 1.2 02:28:25 INFO - mozhttpd == 0.7 02:28:25 INFO - mozinfo == 0.9 02:28:25 INFO - mozleak == 0.1 02:28:25 INFO - mozlog == 3.4 02:28:25 INFO - moznetwork == 0.27 02:28:25 INFO - mozprocess == 0.23 02:28:25 INFO - mozprofile == 0.28 02:28:25 INFO - mozrunner == 6.13 02:28:25 INFO - mozscreenshot == 0.1 02:28:25 INFO - mozsystemmonitor == 0.3 02:28:25 INFO - moztest == 0.7 02:28:25 INFO - mozversion == 1.4 02:28:25 INFO - psutil == 3.1.1 02:28:25 INFO - requests == 1.2.3 02:28:25 INFO - wsgiref == 0.1.2 02:28:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:25 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')]} 02:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:25 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')]} 02:28:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:25 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 0x10747a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa025a6840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10728ed30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107286b20>, '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.71xVysPSSV/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.pS7D4pEdhE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 02:28:25 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 02:28:25 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 02:28:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.71xVysPSSV/Render', 02:28:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:25 INFO - 'HOME': '/Users/cltbld', 02:28:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:25 INFO - 'LOGNAME': 'cltbld', 02:28:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:25 INFO - 'MOZ_NO_REMOTE': '1', 02:28:25 INFO - 'NO_EM_RESTART': '1', 02:28:25 INFO - 'PAGER': '/bin/cat', 02:28:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:25 INFO - 'PWD': '/builds/slave/test', 02:28:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:25 INFO - 'SHELL': '/bin/bash', 02:28:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pS7D4pEdhE/Listeners', 02:28:25 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 02:28:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:25 INFO - 'USER': 'cltbld', 02:28:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:25 INFO - 'XPC_FLAGS': '0x0', 02:28:25 INFO - 'XPC_SERVICE_NAME': '0', 02:28:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:25 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)) 02:28:25 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)) 02:28:25 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)) 02:28:25 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)) 02:28:25 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)) 02:28:25 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)) 02:28:25 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)) 02:28:25 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)) 02:28:25 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)) 02:28:25 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)) 02:28:25 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)) 02:28:25 INFO - Cleaning up... 02:28:25 INFO - Return code: 0 02:28:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:28:25 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')]} 02:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:25 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')]} 02:28:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:25 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 0x10747a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa025a6840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10728ed30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107286b20>, '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.71xVysPSSV/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.pS7D4pEdhE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 02:28:25 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 02:28:25 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 02:28:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.71xVysPSSV/Render', 02:28:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:25 INFO - 'HOME': '/Users/cltbld', 02:28:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:25 INFO - 'LOGNAME': 'cltbld', 02:28:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:25 INFO - 'MOZ_NO_REMOTE': '1', 02:28:25 INFO - 'NO_EM_RESTART': '1', 02:28:25 INFO - 'PAGER': '/bin/cat', 02:28:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:25 INFO - 'PWD': '/builds/slave/test', 02:28:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:25 INFO - 'SHELL': '/bin/bash', 02:28:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pS7D4pEdhE/Listeners', 02:28:25 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 02:28:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:25 INFO - 'USER': 'cltbld', 02:28:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:25 INFO - 'XPC_FLAGS': '0x0', 02:28:25 INFO - 'XPC_SERVICE_NAME': '0', 02:28:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:28:25 INFO - Cleaning up... 02:28:25 INFO - Return code: 0 02:28:25 INFO - Running post-action listener: _resource_record_post_action 02:28:25 INFO - Running post-action listener: _start_resource_monitoring 02:28:25 INFO - Starting resource monitoring. 02:28:25 INFO - [mozharness: 2016-12-10 10:28:25.648772Z] Finished create-virtualenv step (success) 02:28:25 INFO - [mozharness: 2016-12-10 10:28:25.649678Z] Running install step. 02:28:25 INFO - Running pre-action listener: _resource_record_pre_action 02:28:25 INFO - Running main action method: install 02:28:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:25 INFO - Reading from file tmpfile_stdout 02:28:25 INFO - Detecting whether we're running mozinstall >=1.0... 02:28:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:28:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:28:26 INFO - Reading from file tmpfile_stdout 02:28:26 INFO - Output received: 02:28:26 INFO - Usage: mozinstall [options] installer 02:28:26 INFO - Options: 02:28:26 INFO - -h, --help show this help message and exit 02:28:26 INFO - -d DEST, --destination=DEST 02:28:26 INFO - Directory to install application into. [default: 02:28:26 INFO - "/builds/slave/test"] 02:28:26 INFO - --app=APP Application being installed. [default: firefox] 02:28:26 INFO - mkdir: /builds/slave/test/build/application 02:28:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:28:57 INFO - Reading from file tmpfile_stdout 02:28:57 INFO - Output received: 02:28:57 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:28:57 INFO - Running post-action listener: _resource_record_post_action 02:28:57 INFO - [mozharness: 2016-12-10 10:28:57.340136Z] Finished install step (success) 02:28:57 INFO - [mozharness: 2016-12-10 10:28:57.340251Z] Running run-tests step. 02:28:57 INFO - Running pre-action listener: _resource_record_pre_action 02:28:57 INFO - Running main action method: run_tests 02:28:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:28:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:28:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:28:57 INFO - 2016-12-10 02:28:57.354 screenresolution[1657:15365] starting screenresolution argv=screenresolution get 02:28:57 INFO - 2016-12-10 02:28:57.357 screenresolution[1657:15365] Display 0: 1600x1200x32@60 02:28:57 INFO - 2016-12-10 02:28:57.363 screenresolution[1658:15368] starting screenresolution argv=screenresolution list 02:28:57 INFO - Available Modes on Display 0 02:28:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:28:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:28:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:28:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:28:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:28:57 INFO - Intel Iris: 02:28:57 INFO - Chipset Model: Intel Iris 02:28:57 INFO - Type: GPU 02:28:57 INFO - Bus: Built-In 02:28:57 INFO - VRAM (Dynamic, Max): 1536 MB 02:28:57 INFO - Vendor: Intel (0x8086) 02:28:57 INFO - Device ID: 0x0a2e 02:28:57 INFO - Revision ID: 0x0009 02:28:57 INFO - Displays: 02:28:57 INFO - Display: 02:28:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:28:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:28:57 INFO - Display Serial Number: 1600x1200 60 02:28:57 INFO - Main Display: Yes 02:28:57 INFO - Mirror: Off 02:28:57 INFO - Online: Yes 02:28:57 INFO - Rotation: Supported 02:28:57 INFO - Return code: 0 02:28:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:28:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:28:57 INFO - Python 2.7.3 02:28:57 INFO - Return code: 0 02:28:57 INFO - grabbing minidump binary from tooltool 02:28:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:57 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 0x10728ed30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107286b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:28:57 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 02:28:57 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 02:28:57 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 02:28:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:28:57 INFO - Return code: 0 02:28:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:28:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:28:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:28:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:28:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:28:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0363', '--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 02:28:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0363 --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 02:28:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.71xVysPSSV/Render', 02:28:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:57 INFO - 'HOME': '/Users/cltbld', 02:28:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:57 INFO - 'LOGNAME': 'cltbld', 02:28:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:28:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:28:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:57 INFO - 'MOZ_NO_REMOTE': '1', 02:28:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:28:57 INFO - 'NO_EM_RESTART': '1', 02:28:57 INFO - 'PAGER': '/bin/cat', 02:28:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:57 INFO - 'PWD': '/builds/slave/test', 02:28:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:28:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:57 INFO - 'SHELL': '/bin/bash', 02:28:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pS7D4pEdhE/Listeners', 02:28:57 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 02:28:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:57 INFO - 'USER': 'cltbld', 02:28:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:57 INFO - 'XPC_FLAGS': '0x0', 02:28:57 INFO - 'XPC_SERVICE_NAME': '0', 02:28:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0363', '--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 02:28:57 INFO - mozversion application_buildid: 20161210004004 02:28:57 INFO - mozversion application_changeset: 0d52c1ee6bce8b6b8d0b1774ce4b15082e6b4854 02:28:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:28:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:28:57 INFO - mozversion application_name: Firefox 02:28:57 INFO - mozversion application_remotingname: firefox-dev 02:28:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:28:57 INFO - mozversion application_vendor: Mozilla 02:28:57 INFO - mozversion application_version: 52.0a2 02:28:57 INFO - mozversion platform_buildid: 20161210004004 02:28:57 INFO - mozversion platform_changeset: 0d52c1ee6bce8b6b8d0b1774ce4b15082e6b4854 02:28:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:28:57 INFO - mozversion platform_version: 52.0a2 02:28:57 INFO - using testdate: 1481365737 02:28:57 INFO - actual date: 1481365737 02:28:57 INFO - starting webserver on 'localhost:49246' 02:28:57 INFO - SUITE-START | Running 2 tests 02:28:57 INFO - TEST-START | dromaeo_css 02:28:57 INFO - operating with platform_type : mac_ 02:28:57 INFO - Initialising browser for dromaeo_css test... 02:28:58 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpc70i4K/profile http://localhost:49246/getInfo.html) 02:29:01 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 02:29:01 INFO - PROCESS | 1668 | colorDepth:24 02:29:01 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 02:29:01 INFO - PROCESS | 1668 | __metrics 02:29:01 INFO - PROCESS | 1668 | 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" 02:29:01 INFO - PROCESS | 1668 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:29:02 INFO - TEST-INFO | 1668: exit 0 02:29:02 INFO - Browser initialized. 02:29:02 INFO - Running cycle 1/1 for dromaeo_css test... 02:29:02 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpc70i4K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:29:14 INFO - PROCESS | 1672 | RSS: Main: 222617600 02:29:14 INFO - PROCESS | 1672 | 02:30:05 INFO - PROCESS | 1672 | 1481365805589 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] 02:32:19 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 02:32:22 INFO - PROCESS | 1672 | RSS: Main: 416587776 02:32:22 INFO - PROCESS | 1672 | 02:35:24 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 02:35:25 INFO - PROCESS | 1672 | RSS: Main: 348508160 02:35:25 INFO - PROCESS | 1672 | 02:38:27 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 02:38:28 INFO - PROCESS | 1672 | RSS: Main: 331595776 02:38:28 INFO - PROCESS | 1672 | 02:41:30 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 02:41:31 INFO - PROCESS | 1672 | RSS: Main: 322027520 02:41:31 INFO - PROCESS | 1672 | 02:44:35 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 02:44:35 INFO - PROCESS | 1672 | RSS: Main: 311844864 02:44:35 INFO - PROCESS | 1672 | 02:47:38 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 02:47:39 INFO - PROCESS | 1672 | RSS: Main: 369160192 02:47:39 INFO - PROCESS | 1672 | 02:47:39 INFO - PROCESS | 1672 | __start_tp_report 02:47:39 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 02:47:39 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 02:47:39 INFO - PROCESS | 1672 | |i|pagename|runs| 02:47:39 INFO - PROCESS | 1672 | |0;dojo.html;3306.3717893600224;3324.4847048707443;3363.545921300624;3376.966230337697;3414.7951122932627;5595.692236926968;5658.490735833774;5665.688387138705;5671.262735844334;5680;4991.975040124803;5508.586855985797;5537.612367134302;5539.25220095287;5562.415946325638;5051.06555287273;5061.44324124346;5070.74646267687;5110;5203.583713302926;4229.7885105744745;5117.974410127944;5125.615578831584;5151.4333423323405;5179.015986962465;5100.387953445592;5173.146430838907;5225.268462415265;5243.947560524403;5270.631055826094;5199.53204211619;5217.34783152106;5225.947740522584;5263.736813159365;5284.81503147388;5132.973405318914;5194.792208311663;5222.686638801646;5225.608079394061;5292.259083728281;4520.344550040259;5507.834764957058;5515.586331025189;5518.806841760559;5580.051391263454;6133.325334213233;6143.662098584576;6153.108278918271;6156.230471191101;6192.535559833031;6297.842553936142;6322.620642761449;6325.019621958604;6330.556861019731;6331.7783877564525;5534.75093620788;5602.775888964477;5646.85882852932;5723.828285151452;5743.684097374643;6335.271443784024;6354.459870910932;6357.7139028742895;6364.172657554488;6367.42693157618;6093.817185484442;6109.725062372204;6110.358412366726;6127.632342059491;6154.5076393888385;6089.238845144357;6115.797068995582;6125.581905359931;6131.0803379492545;6213.440790328892;5046.44489106198;6090.908636370458;6113.480354169857;6122.204113465221;6127.693615319216;5137.743112844343;5994.490468310155;6116.466682924198;6163.9383606163365;6217.129601855653;4129.814158362881;4177.4778152730905;4902.7058376497525;4904.43598986114;4934.087193869145;4364.978175109104;4388.670849686287;4419.580139886705;4435.556444355603;4446.066326071496;5582.609217354828;5633.295838020247;5638.238837756932;5768.855778605568;5787.971060144672;6152.507799376039;6228.252609686776;6241.843953901189;6287.9056814147825;6291.622502649764;5636.436356364413;5647.0964645656495;5703;5730.226419433407;5816.447437493432;5245.318108645852;5300.284461597712;5362.3565172178805;5382.4886635769535;5397.379301380392;6220.0047992321015;6264.467520260829;6283.2460104787715;6300.180976473029;6322.083297921868;5223.4776522347465;6261.092141639528;6265.968670156803;6281.466075383643;6292.370762923671;6201.689915504297;6281.874362512815;6299.874002519832;6303.275123360689;6321.494280457644;5918.141869428822;6033.366496517802;6063.272407311151;6081.361457046946;6175.042868355412;5990.6705131218205;6012.278526576839;6285.151504546828;6285.685715714288;6290.182276304051;6006.549508786771;6058.818235452944;6068.271807383142;6074.857339703718;6085.817425477243;5741.942580574141;5809.157672137601;6029.849253768691;6036.9094463582205;6061.787837425669;5754.079347304411;5759.107338362561;5769.91345129815;5775.133729940542;5796.565257605781;5874.706264686834;5882.5293976480925;5896.292444906639;5896.970515147397;5899.144624029577;5701.116326969326;5712.228849105484;5733.799317024051;5753.9930512161045;5758.0499217630295 02:47:39 INFO - PROCESS | 1672 | |1;ext.html;462.12196826030515;511.64952007874587;513.802186158329;520.318382918377;550.1115698147493;38348.04129896754;39785;39847.80076099623;41689;41725;41640.167196656046;42180.789096054556;42212.78893605535;42265.36601950973;42457.36313955293;9294.628214871396;9469.621215151386;9485.905140948571;9492.430454172738;9505.42967421957;9430.811383772321;9519.714408567754;9526.28552858535;9529.999350068247;9534.237261019101;9512.096350846694;9562.474063926482;9567.760805979837;9573.377730447499;9595.280353973445;9476.241900647932;9536.99861514538;9539.47532885691;9542.997985211523;9558.713238602853;9378.85931711025;9383.953080234623;9460.337776355589;9477.857832132524;9496.90503094967;30816;37931.86204413872;37974.050648733726;37985;38042.80978595117;3352.949705754416;3522.84147213376;3529.735269854771;3629.8185090745355;3741.2143449875557;28224.576631350545;29614;30450.390992180033;30591;30949.535756963665;2729.56326987683;3155.495120780664;3195.297034652396;3232.563603913465;3259.2992506610954;365807;368214;368542.1572892145;369556;370023;58722;59024.40975590275;59048.86657933903;59116;59117.704411477665;34767.65232347695;35729.28541429157;35744.64255357465;36458;36486.08784780349;14861.925690371696;14959.326830292663;14993.250337483083;15022.924885375503;15049.473268435771;11322.943385283186;11397.658070257905;11398.658040258806;11419.714507137222;11431.885681143249;18856.81143188578;18930.90534547346;18956.336528221447;18957.43127706171;18974.620507590047;6505.642189679518;7555.9244407555225;7608.277213664693;7622.352100071556;7738.883916741253;827.6896163938524;943.5046600534774;954.410307234886;1023.9913685020249;1096.6984079378076;29153.85423072871;29285.707142928724;29762.255943601584;29865.55201671977;29936.85031574828;981.5484876956538;994.1301361308855;1051.5005372448027;1102.0412241349916;1146.810776221914;15428.305726242346;15566.84433155654;15598;15918;15983;674305;680077;680598;682541;688505;57125;57752.71123644355;57788.4221157783;57872.13191802127;57909.4209057904;1235.7281398092405;1278.2713853103828;1341.2489006156584;1400.3698335749157;1403.0389183409602;29613;30448.391032179676;30580.235494112825;30862.53706194364;31542.842285788425;49545;50117.49882501274;50192.24711629256;50267.74330641763;50276.49723502865;541950;544604;545879;546340;593764;23153;26017.089401870842;26815.061472848356;26820.195394138205;27475.31311717223;44489;44851.327230092225;44920;44949;45302.54697452983;38431.231375372896;38618.22763544769;38663.226735465694;38801.805990969864;38947.2210555793;37792;38562.614373855904;38601;38732;39684.801575991936;37038.629613704594;37668.62331376651;37981.810090949366;38031.239375211786;38303.04242393962;33613.32773344568;33804.83097584594;34667.13332166716;34898.65101348954;35030.64969350274;25036.874815625804;25455;25526.361840954123;25847.61228581535;25909.740902591486 02:47:39 INFO - PROCESS | 1672 | |2;jquery.html;320.6152616859768;348.16614208969537;368.8266514738074;369.7190135497023;400.03591344858876;14953.327100280487;15028.849711502882;15470.38118475261;19756.901215493937;19771.91254481003;18795.248190072416;20672.586548269024;20679.896600517015;20739;20741.06665200066;5724.570657200706;5999.580029397944;6009.9399006010035;6041.667708276043;6049.274087109531;3632.891013269606;4164.97917510412;4442.911141777163;4457.821687132527;6723.562968407038;6734.528582999167;6744.055832183498;6749.831254218635;6752.561083529579;6757.932420675804;5517.2551705519645;5558.666480011192;5570.3315602127595;5587;6247.15663385444;4292.570742925714;4908.705477671351;4977.303177555145;5069.594432445396;5076.64463487556;24818.627720584023;25681.35796605081;25756.227313180636;25854.482910341874;25887;2259.367377134405;2611.3993781430377;2613.803964702655;3134.6238451385752;3150.810951342904;12368;14317.856821431757;14756.778648320284;14989.70020599582;15646.374145034186;1837.880537765041;1841.6461819046779;1876.8123187681258;1883.2090521980801;1952.1508143957283;95849;116253;118203;120683.793162069;123068.38465807613;10860.837087443695;11224.887751122547;11350.716232094264;11362.613851091364;11494.195406321645;11766.646835613843;14120.50578229757;14141.787873181911;14145.717085658434;14171.64570885736;8232.300254478436;8306.252437280553;8328.916710832935;8355.456895301786;8466.407351485337;8110.43226974118;8119;8273.875891861628;8278.875816862752;8298.419110662197;6605.2918303495335;6769.221539523018;6795.844706399924;6798.72242798668;6807.421369183576;1808.5026617680244;1844.1701234444554;1845.4740398986494;1847.7505536752635;1849.546861019059;839.9496030238205;848.5714714069355;867.1501928110487;962.6101428921298;1096.627146770102;21498.462538436663;22427.551448970928;22472.775272247396;22557;22666.546669066527;893.5442924108752;937.3485427627734;944.3059351376733;1003.159202862755;1009.6970908727352;10738.570457181626;11788.174827761974;11956.641300760992;11988.340641264815;12003.579874704343;614855;624247.8787606123;630119;632107;635155;86975.56512217398;124164;125652;130081.69918300696;141693;423.2677467980411;436.67467736536486;451.0685434577652;452.53389009320455;457.6819110718019;9344.672936447463;10886.346819190874;11289.322640641587;11558.422078895854;11924.986376158124;36009.459858101625;45069.54930450785;53069;56102.719486402304;56803.863922720484;58915.705421472616;77099;77288;79072.60463697645;83827.19814419399;7817.882731758915;8254.174582541698;8341.082480926972;8430.620622072143;8576.528290944058;9074.867158378926;12899.226046437243;12977.805332920203;13036.41336139857;13311.733765324832;8830.470171789713;11118.555257789598;11262.718432039264;11301.604443844426;11485.885141148816;10799;10938.179636527133;11153.609623663133;11306.717332066764;11494.655160345203;11072.058874995715;11134.665960021212;11679.06567474617;11814.527418903148;11861.881381186422;8164.959175204086;8251.793705157419;8346;8486.575671216538;8560.657573696983;8336.583170841312;8686.78283042929;10239.48802559884;10539;11185.944070279596 02:47:39 INFO - PROCESS | 1672 | |3;mootools.html;1300.005495795717;1396.3646540823922;1479.3416929466387;1607.7749115123884;1834.266293482606;7447.627618619068;7796.025496812898;8146.755597332082;8385.161483851613;8419.747407577766;8306.127856575064;8309.293710034639;8319.417640765148;8328.292095171902;8333.708320208778;2304.4008557775014;2326.895289711967;2337.2871274261342;2337.637666161739;2341.4731685370825;2312.5952958232324;2364.1725396111397;2369.9644505332435;2370.4784947311564;2378;2293.736220334658;2303;2329.440934175794;2332.346942856003;2333.6032874411308;2269.6595510673533;2293.919712810052;2315.1086831569796;2317.9072837086496;2322.837401381904;2317.9420514487106;2339.754325795801;2343.8945247463903;2344.9179278725164;2355.2463211772238;7722.150563438074;7723.7682869514;8317.916820831775;8366.288865446444;8380.455270407405;4399.252127138394;5012.974935125338;5024.397072351305;5209.661372010807;5216.217567364888;3003.984980075086;3454.3091381723502;3538.6284440133672;3542.1321776164746;3545.2732189901008;1063.936163830165;1091.858058452404;1119.1270808769173;1120.585383408136;1122.5565901468874;10773.622923197729;10856.077233435168;10859.511321990529;10867.076298514557;10879.238453308271;4275.722078064916;4462.352958820953;4510.729356238636;4513.661475389359;4518.615917647036;8671.869921951176;8685.174908383693;8692.91307086921;8696.21734043939;8698.695545655872;4994.600431965434;5083.847484575469;5089.982003599295;5092.134337162691;5092.949070509321;3339.0483712141922;4148.377743338463;4424.867253982386;4759.619230461555;4779.808807647724;3647.835847386874;3659.835307411173;3749.250149970017;3752.099496120911;3772.320982223171;1115.130198445214;1132.4337831084458;1150.2810743285447;1159.09590519395;1161.4715304536417;755.4334249313015;844.8141408890034;851.2338894994555;865.4288169808023;869.4392117084572;5091.236314552847;5107.668001579886;5266.420693723688;5380.650257733235;5518.834434966958;422.4023007444493;439.2620397731777;444.1184249265206;444.31353558751806;448.67022738287335;2933.870459872964;3182.0612919188893;3408.7613867029568;3517.753757236969;3554.289142171576;9799.657012004545;10137.64518241858;10242.641507547349;10250.38497690141;10270.435126067989;10240.129588985019;10245.077942985166;10254.53854576531;10256.641017564349;10268.691939241686;7711.537307761552;7842.176571460029;7845.607719613997;7850.528968262037;7877.684892604231;3811.466394704799;5158.819441319536;5212.4340970928215;5234.424213336606;5384.703841288767;9323.813523729623;9425.905740942502;9441.575129119068;9449.196818270524;9470.95264523673;7930.524168549906;9192.31057670664;9448.244140468883;9452.527373631428;9683.128518433376;6657.933420665731;6701.865962680817;6722.092517510269;6734.966325168343;6736.191657000995;7749.302562769378;7760.3403710685225;7831.647575858986;7833.529988200727;7838.490498117604;6954.756583519755;7069.974853646296;7070.681819318246;7152.356287934111;7224.963875180591;6412.454941330039;6452.451541618827;6875.071182042909;7018.052562904147;7055.894161587477;6044.879102418015;6098.359672234529;6425.1968503937005;6612.338766123349;6631.071649969104;4459.643228541774;4682.086993036325;4683.344331793483;4688.296755486705;4864.343313652612;4803.207470767285;4805.591524720298;4808.8797780055775;4942.5304596063315;4971.154903666457 02:47:39 INFO - PROCESS | 1672 | |4;prototype.html;297.5239616613418;305.6301874731574;307.8845432962639;315.8341870517979;316.1747838142449;4708.8822779430575;5385.788197655535;5389.110796718542;5406.864288606003;5471.452854714526;5069.79720811168;5293.920591191135;5314.5748340132795;5376.650517716345;5382.784688612461;3550.6626870447267;3805.9429108563395;3808.3335416302175;3866.516685414323;4037.454943582608;3611.6749492545664;4026.5973402659647;4033.556308806029;4087.6496014791464;4117.567655396185;4042.312806822847;4051.939220911674;4073.2464494068545;4086.3053280942163;4102.0360215349365;3423.3495635829113;4025.389844062384;4070.8982275443054;4077.1641813428178;4082.7958602069925;3365.848536815849;3449.8275086245585;3480.669336413062;3515.4726790981304;3574.160072382988;4304.504981927087;5167.354080739908;5180.663256888328;5201.609879259071;5222.529972302511;4647.070585882803;4650.9070918087;4661.207594708908;4663.694165633628;4664.603508701764;4708.788104535304;5577.944220557783;5589.953475355941;5607.579431542651;5615.382307946123;3915.530136383624;3927;3932.626400491977;3932.8623498177426;3936.3701807710627;5283.392409872876;5448.910217956385;5781.942180578225;5901.881962360729;5904.704764761745;3112.0508244985044;3641.053326135171;3691.8523259069875;3971.2454633620096;4005.5193376794973;4057.857974970862;4068;4072.6945479089186;4084.4281800547947;4091.631753142172;3939.7242193046786;4260.808263628144;4659.95340046602;4674.322223277673;4724.763761811896;5549.916751248735;5629.831105066773;5656.377798442168;5666.518345940642;5698.059820129715;1157.7221466847895;1258.5972488803677;1268.2960956668953;1281.7951125941586;1293.4050336844969;789.269925319089;811.9309858661961;858.420506268469;863.6502216602286;877.7322916510398;4368.825246990084;4963.45402005779;4976.253561965662;5007.24891266313;5041.033996741274;4763.880902977453;5016.498350165028;5020.548150666385;5128.153854614022;5201.401838788512;4143.316352801814;4890.119778439915;4922.384701912261;4964.578010869044;4966.850994470172;2538.41349156777;2593.7795287400404;2668.252294746944;2705.9852555291764;2782.763465105448;5028.1144570785955;5054.848354549369;5697.344805347355;5750.367459579443;5789.392113828072;5358.517733404013;5609.523190528769;5624.790670005968;5637.351704553947;5663.9716801415725;4933.593925731149;5019.648624596243;5242.659227150223;5736.598438109376;5811.128330750336;4691.835785747482;4700.435947686299;5013.824516141917;5536.318348298498;5557.861053473695;4514.413126293561;4667.393238878924;4817.710937343771;4870.97854390424;5445.455454454523;5419.268398766274;5624;5704.429557044263;5896.115582662464;5896.587238893237;3486.7559270850797;3594.993401847487;3747.868824591126;3869.206812603466;3882.0100874276877;5029.823956161516;5153.510416510426;5382.892342153056;5860.30800595622;5865.296164460292;5220.843374698765;5414.945850541601;5610.43895610452;5681.8863622726485;5708;4503.864884053483;5485.780568777363;5570.58220633446;5582.441755824385;5602.775888964559;4386.4736231652405;4863.3434486343895;5161.741912904415;5283.630145889751;5539.847941414523;4946.752662366795;5593.748281327432;5602.803901863415;5603.747831347518;5611.915821262766;4444.799984000663;5320.57435405159;5331.70675612845;5453.863653408696;5478.972605136949 02:47:39 INFO - PROCESS | 1672 | |5;yui.html;682.9760958366456;704.2886684448705;743.3384287983696;755.5731011978236;768.7693691892431;14146.575602731922;14731.337089830957;14745.70508589829;14764.557063288104;14766.335514901828;13085.607431777062;13096.083274170813;13132.803007954864;13138;13201.009924255695;654.5712558274314;667.546068673302;669.8995150727405;800.5636927874293;806.5201205282876;827.0612854410227;827.5944787054331;837.7193640130564;840.2479780596362;841.6254766628853;894.1058941058941;896.0546623312435;896.5158814240302;896.6323807239033;901.3555307954811;688.1604442580044;888.626776753765;888.8844450221494;890.0165317324361;891.4963045879058;525.2856115682669;631.6589041917359;643.5752403413725;644.0596728775993;655.6492276632026;13839;14000.719985600232;14085.929570352186;14090.718185636333;14106.153630782186;12447;12706.237625742486;12800.16798908068;12860.678483037906;12965.22208667483;10683;11048.447577621087;11229.887701123047;11946.761064778655;12213.694657633541;4401.22978478765;4424.336349547561;4616.492185859553;4628.398308219944;4638.884027899296;81821.59089204516;95708;95895;96940.66963798221;96973;10061;10417.010384013507;10468.214883883738;10513.789724205626;10626.468676566295;19448.319308824124;19473.221071157277;19638.803611963984;19706.211751530063;19828.60342793165;7454.105507339154;7454.2545745426105;7521.887171692429;7584.582847943306;7765.689372425039;3488.860445582199;4199.979208023764;4218.303979843353;4232.174725928476;4372.278574035248;3329.700326970536;3444.7244220462303;3445.3453843770103;3448.896533104011;3457.75795694299;643.5680610641285;645.3062957320899;649.6426965169138;657.1260223902199;658.1739916404881;590.8493334199752;605.2222893581726;607.2985701514757;612.7855250662233;620.7889317632026;8338.499690018618;8560.657573696983;8912.643494260157;9135.132162444557;9135.63457461694;527.2197148220655;566.6996491859238;571.711285800668;600.1358044416027;617.9165812615329;4151.219439371398;4221.92340953055;4291.442112525352;4312.935305970413;4414.801333939916;87245;91175;94151;97792.51103744436;114476;66381;92521.5373923126;93414.53292733493;94242.52878735699;98042;75202.24797752062;75926;84548.57725711333;85918;87631;8423.410361274651;8757.956210218908;9928.652497162564;9934.503274836374;9940.204783617148;47913.76043119762;50841.203733794995;52198.73900630624;52952;53952;51608.48391516037;67312.99030514449;71986;75780.24219757953;77375.61312193403;10849.783004340026;12049.638510844688;12114.697132571404;12514.559660866043;12901.419436125208;11721.355325457182;14039.649653871758;14054.156750594997;14549.781753273497;14856.777148343;13015.739685206432;13018.934905325414;13323.933380333036;13502.051101443629;13603.591892243248;12424.627261182179;12900.483980641045;13071.673208169492;13143.868561314264;13973.860261397256;11005.944970275097;11366.545338186379;11394.886051139383;11621.593244236412;11825.053995680153;10927.617533386294;11220.831687524531;11234.3259404436;11242.89634283497;11749.236299640495;9386.530673466163;11219.326840389604;11389.544418223179;11471.024962877915;11565.826512602485 02:47:39 INFO - PROCESS | 1672 | __end_tp_report 02:47:39 INFO - PROCESS | 1672 | __start_cc_report 02:47:39 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2605 02:47:39 INFO - PROCESS | 1672 | __end_cc_report 02:47:39 INFO - PROCESS | 1672 | __startTimestamp1481366859185__endTimestamp 02:47:39 INFO - PROCESS | 1672 | 02:47:39 INFO - PROCESS | 1672 | ------- Summary: start ------- 02:47:39 INFO - PROCESS | 1672 | Number of tests: 6 02:47:39 INFO - PROCESS | 1672 | 02:47:39 INFO - PROCESS | 1672 | [#0] dojo.html Cycles:165 Average:5644.64 Median:5759.11 stddev:663.43 (11.5%) stddev-sans-first:639.60 02:47:39 INFO - PROCESS | 1672 | Values: 3306.4 3324.5 3363.5 3377.0 3414.8 5595.7 5658.5 5665.7 5671.3 5680.0 4992.0 5508.6 5537.6 5539.3 5562.4 5051.1 5061.4 5070.7 5110.0 5203.6 4229.8 5118.0 5125.6 5151.4 5179.0 5100.4 5173.1 5225.3 5243.9 5270.6 5199.5 5217.3 5225.9 5263.7 5284.8 5133.0 5194.8 5222.7 5225.6 5292.3 4520.3 5507.8 5515.6 5518.8 5580.1 6133.3 6143.7 6153.1 6156.2 6192.5 6297.8 6322.6 6325.0 6330.6 6331.8 5534.8 5602.8 5646.9 5723.8 5743.7 6335.3 6354.5 6357.7 6364.2 6367.4 6093.8 6109.7 6110.4 6127.6 6154.5 6089.2 6115.8 6125.6 6131.1 6213.4 5046.4 6090.9 6113.5 6122.2 6127.7 5137.7 5994.5 6116.5 6163.9 6217.1 4129.8 4177.5 4902.7 4904.4 4934.1 4365.0 4388.7 4419.6 4435.6 4446.1 5582.6 5633.3 5638.2 5768.9 5788.0 6152.5 6228.3 6241.8 6287.9 6291.6 5636.4 5647.1 5703.0 5730.2 5816.4 5245.3 5300.3 5362.4 5382.5 5397.4 6220.0 6264.5 6283.2 6300.2 6322.1 5223.5 6261.1 6266.0 6281.5 6292.4 6201.7 6281.9 6299.9 6303.3 6321.5 5918.1 6033.4 6063.3 6081.4 6175.0 5990.7 6012.3 6285.2 6285.7 6290.2 6006.5 6058.8 6068.3 6074.9 6085.8 5741.9 5809.2 6029.8 6036.9 6061.8 5754.1 5759.1 5769.9 5775.1 5796.6 5874.7 5882.5 5896.3 5897.0 5899.1 5701.1 5712.2 5733.8 5754.0 5758.0 02:47:39 INFO - PROCESS | 1672 | 02:47:39 INFO - PROCESS | 1672 | [#1] ext.html Cycles:180 Average:66345.49 Median:38325.54 stddev:147390.36 (384.6%) stddev-sans-first:147720.82 02:47:39 INFO - PROCESS | 1672 | Values: 462.1 511.6 513.8 520.3 550.1 38348.0 39785.0 39847.8 41689.0 41725.0 41640.2 42180.8 42212.8 42265.4 42457.4 9294.6 9469.6 9485.9 9492.4 9505.4 9430.8 9519.7 9526.3 9530.0 9534.2 9512.1 9562.5 9567.8 9573.4 9595.3 9476.2 9537.0 9539.5 9543.0 9558.7 9378.9 9384.0 9460.3 9477.9 9496.9 30816.0 37931.9 37974.1 37985.0 38042.8 3352.9 3522.8 3529.7 3629.8 3741.2 28224.6 29614.0 30450.4 30591.0 30949.5 2729.6 3155.5 3195.3 3232.6 3259.3 365807.0 368214.0 368542.2 369556.0 370023.0 58722.0 59024.4 59048.9 59116.0 59117.7 34767.7 35729.3 35744.6 36458.0 36486.1 14861.9 14959.3 14993.3 15022.9 15049.5 11322.9 11397.7 11398.7 11419.7 11431.9 18856.8 18930.9 18956.3 18957.4 18974.6 6505.6 7555.9 7608.3 7622.4 7738.9 827.7 943.5 954.4 1024.0 1096.7 29153.9 29285.7 29762.3 29865.6 29936.9 981.5 994.1 1051.5 1102.0 1146.8 15428.3 15566.8 15598.0 15918.0 15983.0 674305.0 680077.0 680598.0 682541.0 688505.0 57125.0 57752.7 57788.4 57872.1 57909.4 1235.7 1278.3 1341.2 1400.4 1403.0 29613.0 30448.4 30580.2 30862.5 31542.8 49545.0 50117.5 50192.2 50267.7 50276.5 541950.0 544604.0 545879.0 546340.0 593764.0 23153.0 26017.1 26815.1 26820.2 27475.3 44489.0 44851.3 44920.0 44949.0 45302.5 38431.2 38618.2 38663.2 38801.8 38947.2 37792.0 38562.6 38601.0 38732.0 39684.8 37038.6 37668.6 37981.8 38031.2 38303.0 33613.3 33804.8 34667.1 34898.7 35030.6 25036.9 25455.0 25526.4 25847.6 25909.7 02:47:39 INFO - PROCESS | 1672 | 02:47:39 INFO - PROCESS | 1672 | [#2] jquery.html Cycles:180 Average:35231.61 Median:1735.71 stddev:104280.19 (6007.9%) stddev-sans-first:104539.77 02:47:39 INFO - PROCESS | 1672 | Values: 320.6 348.2 368.8 369.7 400.0 14953.3 15028.8 15470.4 19756.9 19771.9 18795.2 20672.6 20679.9 20739.0 20741.1 5724.6 5999.6 6009.9 6041.7 6049.3 3632.9 4165.0 4442.9 4457.8 6723.6 6734.5 6744.1 6749.8 6752.6 6757.9 5517.3 5558.7 5570.3 5587.0 6247.2 4292.6 4908.7 4977.3 5069.6 5076.6 24818.6 25681.4 25756.2 25854.5 25887.0 2259.4 2611.4 2613.8 3134.6 3150.8 12368.0 14317.9 14756.8 14989.7 15646.4 1837.9 1841.6 1876.8 1883.2 1952.2 95849.0 116253.0 118203.0 120683.8 123068.4 10860.8 11224.9 11350.7 11362.6 11494.2 11766.6 14120.5 14141.8 14145.7 14171.6 8232.3 8306.3 8328.9 8355.5 8466.4 8110.4 8119.0 8273.9 8278.9 8298.4 6605.3 6769.2 6795.8 6798.7 6807.4 1808.5 1844.2 1845.5 1847.8 1849.5 839.9 848.6 867.2 962.6 1096.6 21498.5 22427.6 22472.8 22557.0 22666.5 893.5 937.3 944.3 1003.2 1009.7 10738.6 11788.2 11956.6 11988.3 12003.6 614855.0 624247.9 630119.0 632107.0 635155.0 86975.6 124164.0 125652.0 130081.7 141693.0 423.3 436.7 451.1 452.5 457.7 9344.7 10886.3 11289.3 11558.4 11925.0 36009.5 45069.5 53069.0 56102.7 56803.9 58915.7 77099.0 77288.0 79072.6 83827.2 7817.9 8254.2 8341.1 8430.6 8576.5 9074.9 12899.2 12977.8 13036.4 13311.7 8830.5 11118.6 11262.7 11301.6 11485.9 10799.0 10938.2 11153.6 11306.7 11494.7 11072.1 11134.7 11679.1 11814.5 11861.9 8165.0 8251.8 8346.0 8486.6 8560.7 8336.6 8686.8 10239.5 10539.0 11185.9 02:47:39 INFO - PROCESS | 1672 | 02:47:39 INFO - PROCESS | 1672 | [#3] mootools.html Cycles:180 Average:5311.90 Median:4804.40 stddev:2983.25 (62.1%) stddev-sans-first:2976.38 02:47:39 INFO - PROCESS | 1672 | Values: 1300.0 1396.4 1479.3 1607.8 1834.3 7447.6 7796.0 8146.8 8385.2 8419.7 8306.1 8309.3 8319.4 8328.3 8333.7 2304.4 2326.9 2337.3 2337.6 2341.5 2312.6 2364.2 2370.0 2370.5 2378.0 2293.7 2303.0 2329.4 2332.3 2333.6 2269.7 2293.9 2315.1 2317.9 2322.8 2317.9 2339.8 2343.9 2344.9 2355.2 7722.2 7723.8 8317.9 8366.3 8380.5 4399.3 5013.0 5024.4 5209.7 5216.2 3004.0 3454.3 3538.6 3542.1 3545.3 1063.9 1091.9 1119.1 1120.6 1122.6 10773.6 10856.1 10859.5 10867.1 10879.2 4275.7 4462.4 4510.7 4513.7 4518.6 8671.9 8685.2 8692.9 8696.2 8698.7 4994.6 5083.8 5090.0 5092.1 5092.9 3339.0 4148.4 4424.9 4759.6 4779.8 3647.8 3659.8 3749.3 3752.1 3772.3 1115.1 1132.4 1150.3 1159.1 1161.5 755.4 844.8 851.2 865.4 869.4 5091.2 5107.7 5266.4 5380.7 5518.8 422.4 439.3 444.1 444.3 448.7 2933.9 3182.1 3408.8 3517.8 3554.3 9799.7 10137.6 10242.6 10250.4 10270.4 10240.1 10245.1 10254.5 10256.6 10268.7 7711.5 7842.2 7845.6 7850.5 7877.7 3811.5 5158.8 5212.4 5234.4 5384.7 9323.8 9425.9 9441.6 9449.2 9471.0 7930.5 9192.3 9448.2 9452.5 9683.1 6657.9 6701.9 6722.1 6735.0 6736.2 7749.3 7760.3 7831.6 7833.5 7838.5 6954.8 7070.0 7070.7 7152.4 7225.0 6412.5 6452.5 6875.1 7018.1 7055.9 6044.9 6098.4 6425.2 6612.3 6631.1 4459.6 4682.1 4683.3 4688.3 4864.3 4803.2 4805.6 4808.9 4942.5 4971.2 02:47:39 INFO - PROCESS | 1672 | 02:47:39 INFO - PROCESS | 1672 | [#4] prototype.html Cycles:180 Average:4442.85 Median:4964.02 stddev:1355.59 (27.3%) stddev-sans-first:1323.21 02:47:39 INFO - PROCESS | 1672 | Values: 297.5 305.6 307.9 315.8 316.2 4708.9 5385.8 5389.1 5406.9 5471.5 5069.8 5293.9 5314.6 5376.7 5382.8 3550.7 3805.9 3808.3 3866.5 4037.5 3611.7 4026.6 4033.6 4087.6 4117.6 4042.3 4051.9 4073.2 4086.3 4102.0 3423.3 4025.4 4070.9 4077.2 4082.8 3365.8 3449.8 3480.7 3515.5 3574.2 4304.5 5167.4 5180.7 5201.6 5222.5 4647.1 4650.9 4661.2 4663.7 4664.6 4708.8 5577.9 5590.0 5607.6 5615.4 3915.5 3927.0 3932.6 3932.9 3936.4 5283.4 5448.9 5781.9 5901.9 5904.7 3112.1 3641.1 3691.9 3971.2 4005.5 4057.9 4068.0 4072.7 4084.4 4091.6 3939.7 4260.8 4660.0 4674.3 4724.8 5549.9 5629.8 5656.4 5666.5 5698.1 1157.7 1258.6 1268.3 1281.8 1293.4 789.3 811.9 858.4 863.7 877.7 4368.8 4963.5 4976.3 5007.2 5041.0 4763.9 5016.5 5020.5 5128.2 5201.4 4143.3 4890.1 4922.4 4964.6 4966.9 2538.4 2593.8 2668.3 2706.0 2782.8 5028.1 5054.8 5697.3 5750.4 5789.4 5358.5 5609.5 5624.8 5637.4 5664.0 4933.6 5019.6 5242.7 5736.6 5811.1 4691.8 4700.4 5013.8 5536.3 5557.9 4514.4 4667.4 4817.7 4871.0 5445.5 5419.3 5624.0 5704.4 5896.1 5896.6 3486.8 3595.0 3747.9 3869.2 3882.0 5029.8 5153.5 5382.9 5860.3 5865.3 5220.8 5414.9 5610.4 5681.9 5708.0 4503.9 5485.8 5570.6 5582.4 5602.8 4386.5 4863.3 5161.7 5283.6 5539.8 4946.8 5593.7 5602.8 5603.7 5611.9 4444.8 5320.6 5331.7 5453.9 5479.0 02:47:39 INFO - PROCESS | 1672 | 02:47:39 INFO - PROCESS | 1672 | [#5] yui.html Cycles:180 Average:19784.52 Median:49377.48 stddev:28536.71 (57.8%) stddev-sans-first:28580.72 02:47:39 INFO - PROCESS | 1672 | Values: 683.0 704.3 743.3 755.6 768.8 14146.6 14731.3 14745.7 14764.6 14766.3 13085.6 13096.1 13132.8 13138.0 13201.0 654.6 667.5 669.9 800.6 806.5 827.1 827.6 837.7 840.2 841.6 894.1 896.1 896.5 896.6 901.4 688.2 888.6 888.9 890.0 891.5 525.3 631.7 643.6 644.1 655.6 13839.0 14000.7 14085.9 14090.7 14106.2 12447.0 12706.2 12800.2 12860.7 12965.2 10683.0 11048.4 11229.9 11946.8 12213.7 4401.2 4424.3 4616.5 4628.4 4638.9 81821.6 95708.0 95895.0 96940.7 96973.0 10061.0 10417.0 10468.2 10513.8 10626.5 19448.3 19473.2 19638.8 19706.2 19828.6 7454.1 7454.3 7521.9 7584.6 7765.7 3488.9 4200.0 4218.3 4232.2 4372.3 3329.7 3444.7 3445.3 3448.9 3457.8 643.6 645.3 649.6 657.1 658.2 590.8 605.2 607.3 612.8 620.8 8338.5 8560.7 8912.6 9135.1 9135.6 527.2 566.7 571.7 600.1 617.9 4151.2 4221.9 4291.4 4312.9 4414.8 87245.0 91175.0 94151.0 97792.5 114476.0 66381.0 92521.5 93414.5 94242.5 98042.0 75202.2 75926.0 84548.6 85918.0 87631.0 8423.4 8758.0 9928.7 9934.5 9940.2 47913.8 50841.2 52198.7 52952.0 53952.0 51608.5 67313.0 71986.0 75780.2 77375.6 10849.8 12049.6 12114.7 12514.6 12901.4 11721.4 14039.6 14054.2 14549.8 14856.8 13015.7 13018.9 13323.9 13502.1 13603.6 12424.6 12900.5 13071.7 13143.9 13973.9 11005.9 11366.5 11394.9 11621.6 11825.1 10927.6 11220.8 11234.3 11242.9 11749.2 9386.5 11219.3 11389.5 11471.0 11565.8 02:47:39 INFO - PROCESS | 1672 | -------- Summary: end -------- 02:47:39 INFO - PROCESS | 1672 | 02:47:39 INFO - TEST-INFO | 1672: exit 0 02:47:39 INFO - TEST-OK | dromaeo_css | took 1121906ms 02:47:39 INFO - TEST-START | kraken 02:47:39 INFO - operating with platform_type : mac_ 02:47:39 INFO - Initialising browser for kraken test... 02:47:39 INFO - TEST-INFO | started process 1743 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmp71p8ap/profile http://localhost:49246/getInfo.html) 02:47:42 INFO - PROCESS | 1743 | __metrics Screen width/height:1600/1200 02:47:42 INFO - PROCESS | 1743 | colorDepth:24 02:47:42 INFO - PROCESS | 1743 | Browser inner width/height: 1024/689 02:47:42 INFO - PROCESS | 1743 | __metrics 02:47:42 INFO - PROCESS | 1743 | 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" 02:47:42 INFO - PROCESS | 1743 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:47:43 INFO - TEST-INFO | 1743: exit 0 02:47:43 INFO - Browser initialized. 02:47:43 INFO - Running cycle 1/1 for kraken test... 02:47:43 INFO - TEST-INFO | started process 1745 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmp71p8ap/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:47:55 INFO - PROCESS | 1745 | RSS: Main: 222924800 02:47:55 INFO - PROCESS | 1745 | 02:47:58 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 02:47:59 INFO - PROCESS | 1745 | RSS: Main: 270123008 02:47:59 INFO - PROCESS | 1745 | 02:48:02 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 02:48:02 INFO - PROCESS | 1745 | RSS: Main: 284090368 02:48:02 INFO - PROCESS | 1745 | 02:48:06 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 02:48:06 INFO - PROCESS | 1745 | RSS: Main: 381280256 02:48:06 INFO - PROCESS | 1745 | 02:48:08 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 02:48:09 INFO - PROCESS | 1745 | RSS: Main: 283086848 02:48:09 INFO - PROCESS | 1745 | 02:48:11 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:48:11 INFO - PROCESS | 1745 | RSS: Main: 284237824 02:48:11 INFO - PROCESS | 1745 | 02:48:14 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 02:48:15 INFO - PROCESS | 1745 | RSS: Main: 324931584 02:48:15 INFO - PROCESS | 1745 | 02:48:18 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 02:48:19 INFO - PROCESS | 1745 | RSS: Main: 325931008 02:48:19 INFO - PROCESS | 1745 | 02:48:22 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 02:48:22 INFO - PROCESS | 1745 | RSS: Main: 321753088 02:48:22 INFO - PROCESS | 1745 | 02:48:24 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:48:24 INFO - PROCESS | 1745 | RSS: Main: 296779776 02:48:24 INFO - PROCESS | 1745 | 02:48:26 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:48:27 INFO - PROCESS | 1745 | RSS: Main: 302149632 02:48:27 INFO - PROCESS | 1745 | 02:48:29 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:48:30 INFO - PROCESS | 1745 | RSS: Main: 304095232 02:48:30 INFO - PROCESS | 1745 | 02:48:33 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:48:33 INFO - PROCESS | 1745 | RSS: Main: 312528896 02:48:33 INFO - PROCESS | 1745 | 02:48:36 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:48:37 INFO - PROCESS | 1745 | RSS: Main: 312430592 02:48:37 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 02:48:39 INFO - PROCESS | 1745 | RSS: Main: 357036032 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | __start_tp_report 02:48:39 INFO - PROCESS | 1745 | _x_x_mozilla_page_load 02:48:39 INFO - PROCESS | 1745 | _x_x_mozilla_page_load_details 02:48:39 INFO - PROCESS | 1745 | |i|pagename|runs| 02:48:39 INFO - PROCESS | 1745 | |0;ai-astar;112;112;113;116;116;112;114;114;114;115 02:48:39 INFO - PROCESS | 1745 | |1;audio-beat-detection;154;114;117;157;151;118;129;106;166;140 02:48:39 INFO - PROCESS | 1745 | |2;audio-dft;185;183;183;186;182;184;182;183;185;185 02:48:39 INFO - PROCESS | 1745 | |3;audio-fft;83;59;59;89;62;59;58;59;83;59 02:48:39 INFO - PROCESS | 1745 | |4;audio-oscillator;87;70;121;100;98;78;90;67;69;93 02:48:39 INFO - PROCESS | 1745 | |5;imaging-gaussian-blur;117;95;87;85;86;84;87;84;87;84 02:48:39 INFO - PROCESS | 1745 | |6;imaging-darkroom;151;96;96;97;96;97;113;164;158;96 02:48:39 INFO - PROCESS | 1745 | |7;imaging-desaturate;83;84;78;96;123;132;83;83;84;88 02:48:39 INFO - PROCESS | 1745 | |8;json-parse-financial;56;55;54;53;56;57;57;58;57;56 02:48:39 INFO - PROCESS | 1745 | |9;json-stringify-tinderbox;71;75;72;75;72;68;70;69;70;71 02:48:39 INFO - PROCESS | 1745 | |10;stanford-crypto-aes;85;85;83;85;85;83;84;85;86;82 02:48:39 INFO - PROCESS | 1745 | |11;stanford-crypto-ccm;157;153;166;151;152;161;153;150;198;159 02:48:39 INFO - PROCESS | 1745 | |12;stanford-crypto-pbkdf2;169;173;168;169;173;168;166;168;168;220 02:48:39 INFO - PROCESS | 1745 | |13;stanford-crypto-sha256-iterative;63;81;68;70;85;88;88;73;67;68 02:48:39 INFO - PROCESS | 1745 | __end_tp_report 02:48:39 INFO - PROCESS | 1745 | __start_cc_report 02:48:39 INFO - PROCESS | 1745 | _x_x_mozilla_cycle_collect,1999 02:48:39 INFO - PROCESS | 1745 | __end_cc_report 02:48:39 INFO - PROCESS | 1745 | __startTimestamp1481366919571__endTimestamp 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | ------- Summary: start ------- 02:48:39 INFO - PROCESS | 1745 | Number of tests: 14 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:1.55 (1.4%) stddev-sans-first:1.50 02:48:39 INFO - PROCESS | 1745 | Values: 112.0 112.0 113.0 116.0 116.0 112.0 114.0 114.0 114.0 115.0 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:145.50 stddev:21.13 (14.5%) stddev-sans-first:21.29 02:48:39 INFO - PROCESS | 1745 | Values: 154.0 114.0 117.0 157.0 151.0 118.0 129.0 106.0 166.0 140.0 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.50 stddev:1.40 (0.8%) stddev-sans-first:1.41 02:48:39 INFO - PROCESS | 1745 | Values: 185.0 183.0 183.0 186.0 182.0 184.0 182.0 183.0 185.0 185.0 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | [#3] audio-fft Cycles:10 Average:67.00 Median:60.50 stddev:12.57 (20.8%) stddev-sans-first:11.92 02:48:39 INFO - PROCESS | 1745 | Values: 83.0 59.0 59.0 89.0 62.0 59.0 58.0 59.0 83.0 59.0 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | [#4] audio-oscillator Cycles:10 Average:87.30 Median:82.50 stddev:16.94 (20.5%) stddev-sans-first:17.97 02:48:39 INFO - PROCESS | 1745 | Values: 87.0 70.0 121.0 100.0 98.0 78.0 90.0 67.0 69.0 93.0 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:86.50 stddev:10.16 (11.7%) stddev-sans-first:3.43 02:48:39 INFO - PROCESS | 1745 | Values: 117.0 95.0 87.0 85.0 86.0 84.0 87.0 84.0 87.0 84.0 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | [#6] imaging-darkroom Cycles:10 Average:116.40 Median:96.00 stddev:29.10 (30.3%) stddev-sans-first:28.05 02:48:39 INFO - PROCESS | 1745 | Values: 151.0 96.0 96.0 97.0 96.0 97.0 113.0 164.0 158.0 96.0 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | [#7] imaging-desaturate Cycles:10 Average:93.40 Median:83.50 stddev:18.68 (22.4%) stddev-sans-first:19.43 02:48:39 INFO - PROCESS | 1745 | Values: 83.0 84.0 78.0 96.0 123.0 132.0 83.0 83.0 84.0 88.0 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.50 stddev:1.52 (2.7%) stddev-sans-first:1.62 02:48:39 INFO - PROCESS | 1745 | Values: 56.0 55.0 54.0 53.0 56.0 57.0 57.0 58.0 57.0 56.0 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.50 stddev:2.31 (3.2%) stddev-sans-first:2.45 02:48:39 INFO - PROCESS | 1745 | Values: 71.0 75.0 72.0 75.0 72.0 68.0 70.0 69.0 70.0 71.0 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:85.00 stddev:1.25 (1.5%) stddev-sans-first:1.30 02:48:39 INFO - PROCESS | 1745 | Values: 85.0 85.0 83.0 85.0 85.0 83.0 84.0 85.0 86.0 82.0 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | [#11] stanford-crypto-ccm Cycles:10 Average:160.00 Median:158.00 stddev:14.28 (9.0%) stddev-sans-first:15.10 02:48:39 INFO - PROCESS | 1745 | Values: 157.0 153.0 166.0 151.0 152.0 161.0 153.0 150.0 198.0 159.0 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.20 Median:169.00 stddev:16.25 (9.6%) stddev-sans-first:17.12 02:48:39 INFO - PROCESS | 1745 | Values: 169.0 173.0 168.0 169.0 173.0 168.0 166.0 168.0 168.0 220.0 02:48:39 INFO - PROCESS | 1745 | 02:48:39 INFO - PROCESS | 1745 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.10 Median:77.00 stddev:9.48 (12.3%) stddev-sans-first:8.99 02:48:39 INFO - PROCESS | 1745 | Values: 63.0 81.0 68.0 70.0 85.0 88.0 88.0 73.0 67.0 68.0 02:48:39 INFO - PROCESS | 1745 | -------- Summary: end -------- 02:48:39 INFO - PROCESS | 1745 | 02:48:40 INFO - TEST-INFO | 1745: exit 0 02:48:40 INFO - TEST-OK | kraken | took 60793ms 02:48:40 INFO - SUITE-END | took 1182s 02:48:40 INFO - Completed test suite (00:19:42) 02:48:40 INFO - javascript benchmark 02:48:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3306.3717893600224, 3324.4847048707443, 3363.545921300624, 3376.966230337697, 3414.7951122932627, 5595.692236926968, 5658.490735833774, 5665.688387138705, 5671.262735844334, 5680.0, 4991.975040124803, 5508.586855985797, 5537.612367134302, 5539.25220095287, 5562.415946325638, 5051.06555287273, 5061.44324124346, 5070.74646267687, 5110.0, 5203.583713302926, 4229.7885105744745, 5117.974410127944, 5125.615578831584, 5151.4333423323405, 5179.015986962465, 5100.387953445592, 5173.146430838907, 5225.268462415265, 5243.947560524403, 5270.631055826094, 5199.53204211619, 5217.34783152106, 5225.947740522584, 5263.736813159365, 5284.81503147388, 5132.973405318914, 5194.792208311663, 5222.686638801646, 5225.608079394061, 5292.259083728281, 4520.344550040259, 5507.834764957058, 5515.586331025189, 5518.806841760559, 5580.051391263454, 6133.325334213233, 6143.662098584576, 6153.108278918271, 6156.230471191101, 6192.535559833031, 6297.842553936142, 6322.620642761449, 6325.019621958604, 6330.556861019731, 6331.7783877564525, 5534.75093620788, 5602.775888964477, 5646.85882852932, 5723.828285151452, 5743.684097374643, 6335.271443784024, 6354.459870910932, 6357.7139028742895, 6364.172657554488, 6367.42693157618, 6093.817185484442, 6109.725062372204, 6110.358412366726, 6127.632342059491, 6154.5076393888385, 6089.238845144357, 6115.797068995582, 6125.581905359931, 6131.0803379492545, 6213.440790328892, 5046.44489106198, 6090.908636370458, 6113.480354169857, 6122.204113465221, 6127.693615319216, 5137.743112844343, 5994.490468310155, 6116.466682924198, 6163.9383606163365, 6217.129601855653, 4129.814158362881, 4177.4778152730905, 4902.7058376497525, 4904.43598986114, 4934.087193869145, 4364.978175109104, 4388.670849686287, 4419.580139886705, 4435.556444355603, 4446.066326071496, 5582.609217354828, 5633.295838020247, 5638.238837756932, 5768.855778605568, 5787.971060144672, 6152.507799376039, 6228.252609686776, 6241.843953901189, 6287.9056814147825, 6291.622502649764, 5636.436356364413, 5647.0964645656495, 5703.0, 5730.226419433407, 5816.447437493432, 5245.318108645852, 5300.284461597712, 5362.3565172178805, 5382.4886635769535, 5397.379301380392, 6220.0047992321015, 6264.467520260829, 6283.2460104787715, 6300.180976473029, 6322.083297921868, 5223.4776522347465, 6261.092141639528, 6265.968670156803, 6281.466075383643, 6292.370762923671, 6201.689915504297, 6281.874362512815, 6299.874002519832, 6303.275123360689, 6321.494280457644, 5918.141869428822, 6033.366496517802, 6063.272407311151, 6081.361457046946, 6175.042868355412, 5990.6705131218205, 6012.278526576839, 6285.151504546828, 6285.685715714288, 6290.182276304051, 6006.549508786771, 6058.818235452944, 6068.271807383142, 6074.857339703718, 6085.817425477243, 5741.942580574141, 5809.157672137601, 6029.849253768691, 6036.9094463582205, 6061.787837425669, 5754.079347304411, 5759.107338362561, 5769.91345129815, 5775.133729940542, 5796.565257605781, 5874.706264686834, 5882.5293976480925, 5896.292444906639, 5896.970515147397, 5899.144624029577, 5701.116326969326, 5712.228849105484, 5733.799317024051, 5753.9930512161045, 5758.0499217630295], "value": 5602.727650674665, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.12196826030515, 511.64952007874587, 513.802186158329, 520.318382918377, 550.1115698147493, 38348.04129896754, 39785.0, 39847.80076099623, 41689.0, 41725.0, 41640.167196656046, 42180.789096054556, 42212.78893605535, 42265.36601950973, 42457.36313955293, 9294.628214871396, 9469.621215151386, 9485.905140948571, 9492.430454172738, 9505.42967421957, 9430.811383772321, 9519.714408567754, 9526.28552858535, 9529.999350068247, 9534.237261019101, 9512.096350846694, 9562.474063926482, 9567.760805979837, 9573.377730447499, 9595.280353973445, 9476.241900647932, 9536.99861514538, 9539.47532885691, 9542.997985211523, 9558.713238602853, 9378.85931711025, 9383.953080234623, 9460.337776355589, 9477.857832132524, 9496.90503094967, 30816.0, 37931.86204413872, 37974.050648733726, 37985.0, 38042.80978595117, 3352.949705754416, 3522.84147213376, 3529.735269854771, 3629.8185090745355, 3741.2143449875557, 28224.576631350545, 29614.0, 30450.390992180033, 30591.0, 30949.535756963665, 2729.56326987683, 3155.495120780664, 3195.297034652396, 3232.563603913465, 3259.2992506610954, 365807.0, 368214.0, 368542.1572892145, 369556.0, 370023.0, 58722.0, 59024.40975590275, 59048.86657933903, 59116.0, 59117.704411477665, 34767.65232347695, 35729.28541429157, 35744.64255357465, 36458.0, 36486.08784780349, 14861.925690371696, 14959.326830292663, 14993.250337483083, 15022.924885375503, 15049.473268435771, 11322.943385283186, 11397.658070257905, 11398.658040258806, 11419.714507137222, 11431.885681143249, 18856.81143188578, 18930.90534547346, 18956.336528221447, 18957.43127706171, 18974.620507590047, 6505.642189679518, 7555.9244407555225, 7608.277213664693, 7622.352100071556, 7738.883916741253, 827.6896163938524, 943.5046600534774, 954.410307234886, 1023.9913685020249, 1096.6984079378076, 29153.85423072871, 29285.707142928724, 29762.255943601584, 29865.55201671977, 29936.85031574828, 981.5484876956538, 994.1301361308855, 1051.5005372448027, 1102.0412241349916, 1146.810776221914, 15428.305726242346, 15566.84433155654, 15598.0, 15918.0, 15983.0, 674305.0, 680077.0, 680598.0, 682541.0, 688505.0, 57125.0, 57752.71123644355, 57788.4221157783, 57872.13191802127, 57909.4209057904, 1235.7281398092405, 1278.2713853103828, 1341.2489006156584, 1400.3698335749157, 1403.0389183409602, 29613.0, 30448.391032179676, 30580.235494112825, 30862.53706194364, 31542.842285788425, 49545.0, 50117.49882501274, 50192.24711629256, 50267.74330641763, 50276.49723502865, 541950.0, 544604.0, 545879.0, 546340.0, 593764.0, 23153.0, 26017.089401870842, 26815.061472848356, 26820.195394138205, 27475.31311717223, 44489.0, 44851.327230092225, 44920.0, 44949.0, 45302.54697452983, 38431.231375372896, 38618.22763544769, 38663.226735465694, 38801.805990969864, 38947.2210555793, 37792.0, 38562.614373855904, 38601.0, 38732.0, 39684.801575991936, 37038.629613704594, 37668.62331376651, 37981.810090949366, 38031.239375211786, 38303.04242393962, 33613.32773344568, 33804.83097584594, 34667.13332166716, 34898.65101348954, 35030.64969350274, 25036.874815625804, 25455.0, 25526.361840954123, 25847.61228581535, 25909.740902591486], "value": 19291.802898713413, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.6152616859768, 348.16614208969537, 368.8266514738074, 369.7190135497023, 400.03591344858876, 14953.327100280487, 15028.849711502882, 15470.38118475261, 19756.901215493937, 19771.91254481003, 18795.248190072416, 20672.586548269024, 20679.896600517015, 20739.0, 20741.06665200066, 5724.570657200706, 5999.580029397944, 6009.9399006010035, 6041.667708276043, 6049.274087109531, 3632.891013269606, 4164.97917510412, 4442.911141777163, 4457.821687132527, 6723.562968407038, 6734.528582999167, 6744.055832183498, 6749.831254218635, 6752.561083529579, 6757.932420675804, 5517.2551705519645, 5558.666480011192, 5570.3315602127595, 5587.0, 6247.15663385444, 4292.570742925714, 4908.705477671351, 4977.303177555145, 5069.594432445396, 5076.64463487556, 24818.627720584023, 25681.35796605081, 25756.227313180636, 25854.482910341874, 25887.0, 2259.367377134405, 2611.3993781430377, 2613.803964702655, 3134.6238451385752, 3150.810951342904, 12368.0, 14317.856821431757, 14756.778648320284, 14989.70020599582, 15646.374145034186, 1837.880537765041, 1841.6461819046779, 1876.8123187681258, 1883.2090521980801, 1952.1508143957283, 95849.0, 116253.0, 118203.0, 120683.793162069, 123068.38465807613, 10860.837087443695, 11224.887751122547, 11350.716232094264, 11362.613851091364, 11494.195406321645, 11766.646835613843, 14120.50578229757, 14141.787873181911, 14145.717085658434, 14171.64570885736, 8232.300254478436, 8306.252437280553, 8328.916710832935, 8355.456895301786, 8466.407351485337, 8110.43226974118, 8119.0, 8273.875891861628, 8278.875816862752, 8298.419110662197, 6605.2918303495335, 6769.221539523018, 6795.844706399924, 6798.72242798668, 6807.421369183576, 1808.5026617680244, 1844.1701234444554, 1845.4740398986494, 1847.7505536752635, 1849.546861019059, 839.9496030238205, 848.5714714069355, 867.1501928110487, 962.6101428921298, 1096.627146770102, 21498.462538436663, 22427.551448970928, 22472.775272247396, 22557.0, 22666.546669066527, 893.5442924108752, 937.3485427627734, 944.3059351376733, 1003.159202862755, 1009.6970908727352, 10738.570457181626, 11788.174827761974, 11956.641300760992, 11988.340641264815, 12003.579874704343, 614855.0, 624247.8787606123, 630119.0, 632107.0, 635155.0, 86975.56512217398, 124164.0, 125652.0, 130081.69918300696, 141693.0, 423.2677467980411, 436.67467736536486, 451.0685434577652, 452.53389009320455, 457.6819110718019, 9344.672936447463, 10886.346819190874, 11289.322640641587, 11558.422078895854, 11924.986376158124, 36009.459858101625, 45069.54930450785, 53069.0, 56102.719486402304, 56803.863922720484, 58915.705421472616, 77099.0, 77288.0, 79072.60463697645, 83827.19814419399, 7817.882731758915, 8254.174582541698, 8341.082480926972, 8430.620622072143, 8576.528290944058, 9074.867158378926, 12899.226046437243, 12977.805332920203, 13036.41336139857, 13311.733765324832, 8830.470171789713, 11118.555257789598, 11262.718432039264, 11301.604443844426, 11485.885141148816, 10799.0, 10938.179636527133, 11153.609623663133, 11306.717332066764, 11494.655160345203, 11072.058874995715, 11134.665960021212, 11679.06567474617, 11814.527418903148, 11861.881381186422, 8164.959175204086, 8251.793705157419, 8346.0, 8486.575671216538, 8560.657573696983, 8336.583170841312, 8686.78283042929, 10239.48802559884, 10539.0, 11185.944070279596], "value": 9286.45994431829, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.005495795717, 1396.3646540823922, 1479.3416929466387, 1607.7749115123884, 1834.266293482606, 7447.627618619068, 7796.025496812898, 8146.755597332082, 8385.161483851613, 8419.747407577766, 8306.127856575064, 8309.293710034639, 8319.417640765148, 8328.292095171902, 8333.708320208778, 2304.4008557775014, 2326.895289711967, 2337.2871274261342, 2337.637666161739, 2341.4731685370825, 2312.5952958232324, 2364.1725396111397, 2369.9644505332435, 2370.4784947311564, 2378.0, 2293.736220334658, 2303.0, 2329.440934175794, 2332.346942856003, 2333.6032874411308, 2269.6595510673533, 2293.919712810052, 2315.1086831569796, 2317.9072837086496, 2322.837401381904, 2317.9420514487106, 2339.754325795801, 2343.8945247463903, 2344.9179278725164, 2355.2463211772238, 7722.150563438074, 7723.7682869514, 8317.916820831775, 8366.288865446444, 8380.455270407405, 4399.252127138394, 5012.974935125338, 5024.397072351305, 5209.661372010807, 5216.217567364888, 3003.984980075086, 3454.3091381723502, 3538.6284440133672, 3542.1321776164746, 3545.2732189901008, 1063.936163830165, 1091.858058452404, 1119.1270808769173, 1120.585383408136, 1122.5565901468874, 10773.622923197729, 10856.077233435168, 10859.511321990529, 10867.076298514557, 10879.238453308271, 4275.722078064916, 4462.352958820953, 4510.729356238636, 4513.661475389359, 4518.615917647036, 8671.869921951176, 8685.174908383693, 8692.91307086921, 8696.21734043939, 8698.695545655872, 4994.600431965434, 5083.847484575469, 5089.982003599295, 5092.134337162691, 5092.949070509321, 3339.0483712141922, 4148.377743338463, 4424.867253982386, 4759.619230461555, 4779.808807647724, 3647.835847386874, 3659.835307411173, 3749.250149970017, 3752.099496120911, 3772.320982223171, 1115.130198445214, 1132.4337831084458, 1150.2810743285447, 1159.09590519395, 1161.4715304536417, 755.4334249313015, 844.8141408890034, 851.2338894994555, 865.4288169808023, 869.4392117084572, 5091.236314552847, 5107.668001579886, 5266.420693723688, 5380.650257733235, 5518.834434966958, 422.4023007444493, 439.2620397731777, 444.1184249265206, 444.31353558751806, 448.67022738287335, 2933.870459872964, 3182.0612919188893, 3408.7613867029568, 3517.753757236969, 3554.289142171576, 9799.657012004545, 10137.64518241858, 10242.641507547349, 10250.38497690141, 10270.435126067989, 10240.129588985019, 10245.077942985166, 10254.53854576531, 10256.641017564349, 10268.691939241686, 7711.537307761552, 7842.176571460029, 7845.607719613997, 7850.528968262037, 7877.684892604231, 3811.466394704799, 5158.819441319536, 5212.4340970928215, 5234.424213336606, 5384.703841288767, 9323.813523729623, 9425.905740942502, 9441.575129119068, 9449.196818270524, 9470.95264523673, 7930.524168549906, 9192.31057670664, 9448.244140468883, 9452.527373631428, 9683.128518433376, 6657.933420665731, 6701.865962680817, 6722.092517510269, 6734.966325168343, 6736.191657000995, 7749.302562769378, 7760.3403710685225, 7831.647575858986, 7833.529988200727, 7838.490498117604, 6954.756583519755, 7069.974853646296, 7070.681819318246, 7152.356287934111, 7224.963875180591, 6412.454941330039, 6452.451541618827, 6875.071182042909, 7018.052562904147, 7055.894161587477, 6044.879102418015, 6098.359672234529, 6425.1968503937005, 6612.338766123349, 6631.071649969104, 4459.643228541774, 4682.086993036325, 4683.344331793483, 4688.296755486705, 4864.343313652612, 4803.207470767285, 4805.591524720298, 4808.8797780055775, 4942.5304596063315, 4971.154903666457], "value": 4224.685306256471, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.5239616613418, 305.6301874731574, 307.8845432962639, 315.8341870517979, 316.1747838142449, 4708.8822779430575, 5385.788197655535, 5389.110796718542, 5406.864288606003, 5471.452854714526, 5069.79720811168, 5293.920591191135, 5314.5748340132795, 5376.650517716345, 5382.784688612461, 3550.6626870447267, 3805.9429108563395, 3808.3335416302175, 3866.516685414323, 4037.454943582608, 3611.6749492545664, 4026.5973402659647, 4033.556308806029, 4087.6496014791464, 4117.567655396185, 4042.312806822847, 4051.939220911674, 4073.2464494068545, 4086.3053280942163, 4102.0360215349365, 3423.3495635829113, 4025.389844062384, 4070.8982275443054, 4077.1641813428178, 4082.7958602069925, 3365.848536815849, 3449.8275086245585, 3480.669336413062, 3515.4726790981304, 3574.160072382988, 4304.504981927087, 5167.354080739908, 5180.663256888328, 5201.609879259071, 5222.529972302511, 4647.070585882803, 4650.9070918087, 4661.207594708908, 4663.694165633628, 4664.603508701764, 4708.788104535304, 5577.944220557783, 5589.953475355941, 5607.579431542651, 5615.382307946123, 3915.530136383624, 3927.0, 3932.626400491977, 3932.8623498177426, 3936.3701807710627, 5283.392409872876, 5448.910217956385, 5781.942180578225, 5901.881962360729, 5904.704764761745, 3112.0508244985044, 3641.053326135171, 3691.8523259069875, 3971.2454633620096, 4005.5193376794973, 4057.857974970862, 4068.0, 4072.6945479089186, 4084.4281800547947, 4091.631753142172, 3939.7242193046786, 4260.808263628144, 4659.95340046602, 4674.322223277673, 4724.763761811896, 5549.916751248735, 5629.831105066773, 5656.377798442168, 5666.518345940642, 5698.059820129715, 1157.7221466847895, 1258.5972488803677, 1268.2960956668953, 1281.7951125941586, 1293.4050336844969, 789.269925319089, 811.9309858661961, 858.420506268469, 863.6502216602286, 877.7322916510398, 4368.825246990084, 4963.45402005779, 4976.253561965662, 5007.24891266313, 5041.033996741274, 4763.880902977453, 5016.498350165028, 5020.548150666385, 5128.153854614022, 5201.401838788512, 4143.316352801814, 4890.119778439915, 4922.384701912261, 4964.578010869044, 4966.850994470172, 2538.41349156777, 2593.7795287400404, 2668.252294746944, 2705.9852555291764, 2782.763465105448, 5028.1144570785955, 5054.848354549369, 5697.344805347355, 5750.367459579443, 5789.392113828072, 5358.517733404013, 5609.523190528769, 5624.790670005968, 5637.351704553947, 5663.9716801415725, 4933.593925731149, 5019.648624596243, 5242.659227150223, 5736.598438109376, 5811.128330750336, 4691.835785747482, 4700.435947686299, 5013.824516141917, 5536.318348298498, 5557.861053473695, 4514.413126293561, 4667.393238878924, 4817.710937343771, 4870.97854390424, 5445.455454454523, 5419.268398766274, 5624.0, 5704.429557044263, 5896.115582662464, 5896.587238893237, 3486.7559270850797, 3594.993401847487, 3747.868824591126, 3869.206812603466, 3882.0100874276877, 5029.823956161516, 5153.510416510426, 5382.892342153056, 5860.30800595622, 5865.296164460292, 5220.843374698765, 5414.945850541601, 5610.43895610452, 5681.8863622726485, 5708.0, 4503.864884053483, 5485.780568777363, 5570.58220633446, 5582.441755824385, 5602.775888964559, 4386.4736231652405, 4863.3434486343895, 5161.741912904415, 5283.630145889751, 5539.847941414523, 4946.752662366795, 5593.748281327432, 5602.803901863415, 5603.747831347518, 5611.915821262766, 4444.799984000663, 5320.57435405159, 5331.70675612845, 5453.863653408696, 5478.972605136949], "value": 4007.262931933678, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.9760958366456, 704.2886684448705, 743.3384287983696, 755.5731011978236, 768.7693691892431, 14146.575602731922, 14731.337089830957, 14745.70508589829, 14764.557063288104, 14766.335514901828, 13085.607431777062, 13096.083274170813, 13132.803007954864, 13138.0, 13201.009924255695, 654.5712558274314, 667.546068673302, 669.8995150727405, 800.5636927874293, 806.5201205282876, 827.0612854410227, 827.5944787054331, 837.7193640130564, 840.2479780596362, 841.6254766628853, 894.1058941058941, 896.0546623312435, 896.5158814240302, 896.6323807239033, 901.3555307954811, 688.1604442580044, 888.626776753765, 888.8844450221494, 890.0165317324361, 891.4963045879058, 525.2856115682669, 631.6589041917359, 643.5752403413725, 644.0596728775993, 655.6492276632026, 13839.0, 14000.719985600232, 14085.929570352186, 14090.718185636333, 14106.153630782186, 12447.0, 12706.237625742486, 12800.16798908068, 12860.678483037906, 12965.22208667483, 10683.0, 11048.447577621087, 11229.887701123047, 11946.761064778655, 12213.694657633541, 4401.22978478765, 4424.336349547561, 4616.492185859553, 4628.398308219944, 4638.884027899296, 81821.59089204516, 95708.0, 95895.0, 96940.66963798221, 96973.0, 10061.0, 10417.010384013507, 10468.214883883738, 10513.789724205626, 10626.468676566295, 19448.319308824124, 19473.221071157277, 19638.803611963984, 19706.211751530063, 19828.60342793165, 7454.105507339154, 7454.2545745426105, 7521.887171692429, 7584.582847943306, 7765.689372425039, 3488.860445582199, 4199.979208023764, 4218.303979843353, 4232.174725928476, 4372.278574035248, 3329.700326970536, 3444.7244220462303, 3445.3453843770103, 3448.896533104011, 3457.75795694299, 643.5680610641285, 645.3062957320899, 649.6426965169138, 657.1260223902199, 658.1739916404881, 590.8493334199752, 605.2222893581726, 607.2985701514757, 612.7855250662233, 620.7889317632026, 8338.499690018618, 8560.657573696983, 8912.643494260157, 9135.132162444557, 9135.63457461694, 527.2197148220655, 566.6996491859238, 571.711285800668, 600.1358044416027, 617.9165812615329, 4151.219439371398, 4221.92340953055, 4291.442112525352, 4312.935305970413, 4414.801333939916, 87245.0, 91175.0, 94151.0, 97792.51103744436, 114476.0, 66381.0, 92521.5373923126, 93414.53292733493, 94242.52878735699, 98042.0, 75202.24797752062, 75926.0, 84548.57725711333, 85918.0, 87631.0, 8423.410361274651, 8757.956210218908, 9928.652497162564, 9934.503274836374, 9940.204783617148, 47913.76043119762, 50841.203733794995, 52198.73900630624, 52952.0, 53952.0, 51608.48391516037, 67312.99030514449, 71986.0, 75780.24219757953, 77375.61312193403, 10849.783004340026, 12049.638510844688, 12114.697132571404, 12514.559660866043, 12901.419436125208, 11721.355325457182, 14039.649653871758, 14054.156750594997, 14549.781753273497, 14856.777148343, 13015.739685206432, 13018.934905325414, 13323.933380333036, 13502.051101443629, 13603.591892243248, 12424.627261182179, 12900.483980641045, 13071.673208169492, 13143.868561314264, 13973.860261397256, 11005.944970275097, 11366.545338186379, 11394.886051139383, 11621.593244236412, 11825.053995680153, 10927.617533386294, 11220.831687524531, 11234.3259404436, 11242.89634283497, 11749.236299640495, 9386.530673466163, 11219.326840389604, 11389.544418223179, 11471.024962877915, 11565.826512602485], "value": 7171.901607358006, "unit": "score"}], "name": "dromaeo_css", "value": 7041.382149470183, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 113.0, 116.0, 116.0, 112.0, 114.0, 114.0, 114.0, 115.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 114.0, 117.0, 157.0, 151.0, 118.0, 129.0, 106.0, 166.0, 140.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 183.0, 186.0, 182.0, 184.0, 182.0, 183.0, 185.0, 185.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 59.0, 89.0, 62.0, 59.0, 58.0, 59.0, 83.0, 59.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 121.0, 100.0, 98.0, 78.0, 90.0, 67.0, 69.0, 93.0], "value": 87.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 95.0, 87.0, 85.0, 86.0, 84.0, 87.0, 84.0, 87.0, 84.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 96.0, 96.0, 97.0, 96.0, 97.0, 113.0, 164.0, 158.0, 96.0], "value": 116.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 78.0, 96.0, 123.0, 132.0, 83.0, 83.0, 84.0, 88.0], "value": 93.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 53.0, 56.0, 57.0, 57.0, 58.0, 57.0, 56.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 75.0, 72.0, 75.0, 72.0, 68.0, 70.0, 69.0, 70.0, 71.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 83.0, 85.0, 85.0, 83.0, 84.0, 85.0, 86.0, 82.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 153.0, 166.0, 151.0, 152.0, 161.0, 153.0, 150.0, 198.0, 159.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 173.0, 168.0, 169.0, 173.0, 168.0, 166.0, 168.0, 168.0, 220.0], "value": 174.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 81.0, 68.0, 70.0, 85.0, 88.0, 88.0, 73.0, 67.0, 68.0], "value": 75.1, "unit": "score"}], "name": "kraken", "value": 1507.3, "alertThreshold": 2.0}]} 02:48:40 INFO - Return code: 0 02:48:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:48:40 INFO - # TBPL SUCCESS # 02:48:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:48:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:48:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:48:40 INFO - 2016-12-10 02:48:40.871 screenresolution[1749:17878] starting screenresolution argv=screenresolution get 02:48:40 INFO - 2016-12-10 02:48:40.874 screenresolution[1749:17878] Display 0: 1600x1200x32@60 02:48:40 INFO - 2016-12-10 02:48:40.880 screenresolution[1750:17882] starting screenresolution argv=screenresolution list 02:48:40 INFO - Available Modes on Display 0 02:48:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:48:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:48:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:48:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:48:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:48:41 INFO - Intel Iris: 02:48:41 INFO - Chipset Model: Intel Iris 02:48:41 INFO - Type: GPU 02:48:41 INFO - Bus: Built-In 02:48:41 INFO - VRAM (Dynamic, Max): 1536 MB 02:48:41 INFO - Vendor: Intel (0x8086) 02:48:41 INFO - Device ID: 0x0a2e 02:48:41 INFO - Revision ID: 0x0009 02:48:41 INFO - Displays: 02:48:41 INFO - Display: 02:48:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:48:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:48:41 INFO - Display Serial Number: 1600x1200 60 02:48:41 INFO - Main Display: Yes 02:48:41 INFO - Mirror: Off 02:48:41 INFO - Online: Yes 02:48:41 INFO - Rotation: Supported 02:48:41 INFO - Return code: 0 02:48:41 INFO - Running post-action listener: _resource_record_post_action 02:48:41 INFO - [mozharness: 2016-12-10 10:48:41.132323Z] Finished run-tests step (success) 02:48:41 INFO - Running post-run listener: _resource_record_post_run 02:48:41 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 32193536; Write bytes: 662213632; Read time: 197; Write time: 1896 02:48:41 INFO - TinderboxPrint: CPU usage
53.4% 02:48:41 INFO - TinderboxPrint: I/O read bytes / time
32,193,536 / 197 02:48:41 INFO - TinderboxPrint: I/O write bytes / time
662,213,632 / 1,896 02:48:41 INFO - TinderboxPrint: CPU idle
2,265.6 (46.6%) 02:48:41 INFO - TinderboxPrint: CPU system
969.1 (19.9%) 02:48:41 INFO - TinderboxPrint: CPU user
1,627.1 (33.5%) 02:48:41 INFO - TinderboxPrint: Swap in / out
376,500,224 / 0 02:48:41 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342481408; Write bytes: 338146304; Read time: 26797; Write time: 702 02:48:41 INFO - run-tests - Wall time: 1184s; CPU: 53.0%; Read bytes: 31263744; Write bytes: 309477376; Read time: 184; Write time: 1144 02:48:41 INFO - Running post-run listener: _upload_blobber_files 02:48:41 INFO - Blob upload gear active. 02:48:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:48:41 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 02:48:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:48:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:48:42 INFO - (blobuploader) - INFO - Open directory for files ... 02:48:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:48:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:48:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:43 INFO - (blobuploader) - INFO - Done attempting. 02:48:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:48:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:48:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:44 INFO - (blobuploader) - INFO - Done attempting. 02:48:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:48:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:48:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:47 INFO - (blobuploader) - INFO - Done attempting. 02:48:47 INFO - (blobuploader) - INFO - Iteration through files over. 02:48:47 INFO - Return code: 0 02:48:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:48:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:48:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7e5b0b6dd2fadf15e054e146ccfbe7408d0d793b8b7fa214b4ff03b6b7e6897b8b196452f5bcc8a036c6429552a1d4fad86099b2130a57dd9b167a409c2ea49", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/46dea5a97e8c82ab6069eb3f43782baf79888a69194614686e056c21afc2428c346493c0484921590310bd140750e1c70cdb102f4a53741c63e16f7a2ae701a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dbbabcc1309be57c8cd348659986d570e81842b6ba1f840987ef9a51e131ccb4649be81d05574ddb5eb9cc55079525e4617ccff7f57028e2f1e2a66301cb4de4"} 02:48:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:48:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:48:47 INFO - Contents: 02:48:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7e5b0b6dd2fadf15e054e146ccfbe7408d0d793b8b7fa214b4ff03b6b7e6897b8b196452f5bcc8a036c6429552a1d4fad86099b2130a57dd9b167a409c2ea49", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/46dea5a97e8c82ab6069eb3f43782baf79888a69194614686e056c21afc2428c346493c0484921590310bd140750e1c70cdb102f4a53741c63e16f7a2ae701a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dbbabcc1309be57c8cd348659986d570e81842b6ba1f840987ef9a51e131ccb4649be81d05574ddb5eb9cc55079525e4617ccff7f57028e2f1e2a66301cb4de4"} 02:48:47 INFO - Running post-run listener: copy_logs_to_upload_dir 02:48:47 INFO - Copying logs to upload dir... 02:48:47 INFO - mkdir: /builds/slave/test/build/upload/logs 02:48:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.722330 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-12-10 02:48:47.412627) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-10 02:48:47.416411) ========= 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.71xVysPSSV/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.pS7D4pEdhE/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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/Mozilla-Aurora/sha512/d7e5b0b6dd2fadf15e054e146ccfbe7408d0d793b8b7fa214b4ff03b6b7e6897b8b196452f5bcc8a036c6429552a1d4fad86099b2130a57dd9b167a409c2ea49", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/46dea5a97e8c82ab6069eb3f43782baf79888a69194614686e056c21afc2428c346493c0484921590310bd140750e1c70cdb102f4a53741c63e16f7a2ae701a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dbbabcc1309be57c8cd348659986d570e81842b6ba1f840987ef9a51e131ccb4649be81d05574ddb5eb9cc55079525e4617ccff7f57028e2f1e2a66301cb4de4"} build_url:https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011214 build_url: 'https://queue.taskcluster.net/v1/task/ZF33PbefTNa5I0UAtROxHg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7e5b0b6dd2fadf15e054e146ccfbe7408d0d793b8b7fa214b4ff03b6b7e6897b8b196452f5bcc8a036c6429552a1d4fad86099b2130a57dd9b167a409c2ea49", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/46dea5a97e8c82ab6069eb3f43782baf79888a69194614686e056c21afc2428c346493c0484921590310bd140750e1c70cdb102f4a53741c63e16f7a2ae701a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dbbabcc1309be57c8cd348659986d570e81842b6ba1f840987ef9a51e131ccb4649be81d05574ddb5eb9cc55079525e4617ccff7f57028e2f1e2a66301cb4de4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-10 02:48:47.451434) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 02:48:47.451770) ========= 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.71xVysPSSV/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.pS7D4pEdhE/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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.004942 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 02:48:47.500743) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-10 02:48:47.501078) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-10 02:48:47.501419) ========= ========= Total master_lag: 0.18 =========