builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0096 starttime: 1484259591.17 results: success (0) buildid: 20170112121216 builduid: b746a09e422e4a7f88526c25de345da0 revision: 8d0ece73d3278ee81807f7d5dcdd402f994954dc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 14:19:51.170425) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 14:19:51.170912) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 14:19:51.171231) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 14:19:51.196003) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 14:19:51.196411) ========= 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.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 --2017-01-12 14:19:51-- 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% 43.0M=0s 2017-01-12 14:19:51 (43.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101981 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 14:19:51.321939) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 14:19:51.322291) ========= 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.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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.019557 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 14:19:51.371045) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-12 14:19:51.371397) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8d0ece73d3278ee81807f7d5dcdd402f994954dc --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 8d0ece73d3278ee81807f7d5dcdd402f994954dc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 2017-01-12 14:19:51,447 truncating revision to first 12 chars 2017-01-12 14:19:51,447 Setting DEBUG logging. 2017-01-12 14:19:51,447 attempt 1/10 2017-01-12 14:19:51,447 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8d0ece73d327?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-12 14:19:52,337 unpacking tar archive at: mozilla-aurora-8d0ece73d327/testing/mozharness/ program finished with exit code 0 elapsedTime=1.151973 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-12 14:19:52.547067) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 14:19:52.547404) ========= script_repo_revision: 8d0ece73d3278ee81807f7d5dcdd402f994954dc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 14:19:52.547814) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 14:19:52.548111) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 14:19:52.566031) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-12 14:19:52.566419) ========= /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.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 14:19:52 INFO - MultiFileLogger online at 20170112 14:19:52 in /builds/slave/test 14:19:52 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 14:19:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:19:52 INFO - {'append_to_log': False, 14:19:52 INFO - 'base_work_dir': '/builds/slave/test', 14:19:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:19:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:19:52 INFO - 'branch': 'Mozilla-Aurora', 14:19:52 INFO - 'buildbot_json_path': 'buildprops.json', 14:19:52 INFO - 'config_files': ('talos/mac_config.py',), 14:19:52 INFO - 'default_actions': ('clobber', 14:19:52 INFO - 'read-buildbot-config', 14:19:52 INFO - 'download-and-extract', 14:19:52 INFO - 'populate-webroot', 14:19:52 INFO - 'create-virtualenv', 14:19:52 INFO - 'install', 14:19:52 INFO - 'run-tests'), 14:19:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:19:52 INFO - 'download_minidump_stackwalk': True, 14:19:52 INFO - 'download_symbols': 'ondemand', 14:19:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:19:52 INFO - 'tooltool.py': '/tools/tooltool.py', 14:19:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:19:52 INFO - '/tools/misc-python/virtualenv.py')}, 14:19:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:19:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:19:52 INFO - 'installer_path': 'installer.exe', 14:19:52 INFO - 'log_level': 'info', 14:19:52 INFO - 'log_name': 'talos', 14:19:52 INFO - 'log_to_console': True, 14:19:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:19:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:19:52 INFO - 'opt_config_files': (), 14:19:52 INFO - 'pip_index': False, 14:19:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:19:52 INFO - 'cmd': ('bash', 14:19:52 INFO - '-c', 14:19:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:19:52 INFO - 'enabled': True, 14:19:52 INFO - 'halt_on_failure': False, 14:19:52 INFO - 'name': 'check_screen_resolution'},), 14:19:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:19:52 INFO - 'cmd': ('bash', 14:19:52 INFO - '-c', 14:19:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:19:52 INFO - 'enabled': True, 14:19:52 INFO - 'halt_on_failure': False, 14:19:52 INFO - 'name': 'check_screen_resolution'},), 14:19:52 INFO - 'run_cmd_checks_enabled': True, 14:19:52 INFO - 'sps_profile': False, 14:19:52 INFO - 'sps_profile_interval': 0, 14:19:52 INFO - 'suite': 'dromaeojs', 14:19:52 INFO - 'system_bits': '32', 14:19:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:19:52 INFO - 'title': 't-yosemite-r7-0096', 14:19:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:19:52 INFO - 'use_talos_json': True, 14:19:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:19:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:19:52 INFO - 'work_dir': 'build'} 14:19:52 INFO - [mozharness: 2017-01-12 22:19:52.772065Z] Running clobber step. 14:19:52 INFO - Running pre-action listener: _resource_record_pre_action 14:19:52 INFO - Running main action method: clobber 14:19:52 INFO - rmtree: /builds/slave/test/build 14:19:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:19:54 INFO - Running post-action listener: _resource_record_post_action 14:19:54 INFO - [mozharness: 2017-01-12 22:19:54.397604Z] Finished clobber step (success) 14:19:54 INFO - [mozharness: 2017-01-12 22:19:54.397767Z] Running read-buildbot-config step. 14:19:54 INFO - Running pre-action listener: _resource_record_pre_action 14:19:54 INFO - Running main action method: read_buildbot_config 14:19:54 INFO - Using buildbot properties: 14:19:54 INFO - { 14:19:54 INFO - "project": "", 14:19:54 INFO - "product": "firefox", 14:19:54 INFO - "script_repo_revision": "production", 14:19:54 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 14:19:54 INFO - "repository": "", 14:19:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 14:19:54 INFO - "buildid": "20170112121216", 14:19:54 INFO - "pgo_build": "False", 14:19:54 INFO - "basedir": "/builds/slave/test", 14:19:54 INFO - "buildnumber": 70, 14:19:54 INFO - "slavename": "t-yosemite-r7-0096", 14:19:54 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 14:19:54 INFO - "platform": "yosemite_r7", 14:19:54 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:19:54 INFO - "branch": "mozilla-aurora", 14:19:54 INFO - "repo_path": "releases/mozilla-aurora", 14:19:54 INFO - "revision": "8d0ece73d3278ee81807f7d5dcdd402f994954dc", 14:19:54 INFO - "stage_platform": "macosx64", 14:19:54 INFO - "builduid": "b746a09e422e4a7f88526c25de345da0", 14:19:54 INFO - "slavebuilddir": "test" 14:19:54 INFO - } 14:19:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 14:19:54 INFO - Running post-action listener: _resource_record_post_action 14:19:54 INFO - [mozharness: 2017-01-12 22:19:54.401963Z] Finished read-buildbot-config step (success) 14:19:54 INFO - [mozharness: 2017-01-12 22:19:54.402109Z] Running download-and-extract step. 14:19:54 INFO - Running pre-action listener: _resource_record_pre_action 14:19:54 INFO - Running main action method: download_and_extract 14:19:54 INFO - mkdir: /builds/slave/test/build/tests 14:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:54 INFO - trying https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 14:19:54 INFO - Downloading https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/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 14:19:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/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 14:19:55 INFO - Downloaded 1199 bytes. 14:19:55 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 14:19:55 INFO - Using the following test package requirements: 14:19:55 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:19:55 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:19:55 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 14:19:55 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:19:55 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 14:19:55 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:19:55 INFO - u'jsshell-mac.zip'], 14:19:55 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:19:55 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 14:19:55 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:19:55 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:19:55 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 14:19:55 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:19:55 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 14:19:55 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:19:55 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 14:19:55 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:19:55 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 14:19:55 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:19:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:19:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:19:55 INFO - Fetch https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:19:56 INFO - Http code: 200 14:19:56 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 14:19:56 INFO - x-amz-cf-id: foWTewb88CETpj8OyZThaY-cyEE2FgjWy6SYDlJxbAr82X16AwitPg== 14:19:56 INFO - x-amz-version-id: rzjpsBYDXAvwNaXrLfFgphaveBzRAH3Y 14:19:56 INFO - Expected file size: 16275661 14:19:56 INFO - Obtained file size: 16275661 14:20:03 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 14:20:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:20:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:20:03 INFO - Fetch https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:20:03 INFO - Http code: 200 14:20:03 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 14:20:03 INFO - x-amz-cf-id: wmbl3sMwqyEln3nY5IQaT_CIv44FM49_tZ0-YIDofy3DtzypqcFMyg== 14:20:03 INFO - x-amz-version-id: rzjpsBYDXAvwNaXrLfFgphaveBzRAH3Y 14:20:04 INFO - Expected file size: 16275661 14:20:04 INFO - Obtained file size: 16275661 14:20:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 14:20:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 14:20:10 INFO - Fetch https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 14:20:11 INFO - Http code: 200 14:20:11 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 14:20:11 INFO - x-amz-cf-id: Rnuu2XQnr3X0Utoy_Wk2j5QjOc0HQLt4MZYQiWweIs6DEExA8WXUwg== 14:20:11 INFO - x-amz-version-id: GjNqioVMR_doDCRU5bJDQrXAw0C3Ptbq 14:20:11 INFO - Expected file size: 14409038 14:20:11 INFO - Obtained file size: 14409038 14:20:11 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')]} 14:20:11 INFO - trying https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:20:11 INFO - Downloading https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 14:20:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/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 14:20:14 INFO - Downloaded 109987463 bytes. 14:20:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:20:14 INFO - mkdir: /builds/slave/test/properties 14:20:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:20:14 INFO - Writing to file /builds/slave/test/properties/build_url 14:20:14 INFO - Contents: 14:20:14 INFO - build_url:https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:20:15 INFO - Running post-action listener: _resource_record_post_action 14:20:15 INFO - Running post-action listener: set_extra_try_arguments 14:20:15 INFO - [mozharness: 2017-01-12 22:20:15.075051Z] Finished download-and-extract step (success) 14:20:15 INFO - [mozharness: 2017-01-12 22:20:15.075202Z] Running populate-webroot step. 14:20:15 INFO - Running pre-action listener: _resource_record_pre_action 14:20:15 INFO - Running main action method: populate_webroot 14:20:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:20:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:20:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:20:15 INFO - '--tppagecycles', 14:20:15 INFO - '7'], 14:20:15 INFO - 'tests': ['tsvgm']}}, 14:20:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:20:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:20:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:20:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:20:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:20:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:20:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:15 INFO - 'talos_options': ['--disable-e10s'], 14:20:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:20:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:20:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:15 INFO - 'talos_options': ['--disable-e10s'], 14:20:15 INFO - 'tests': ['damp', 'tps']}, 14:20:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:15 INFO - 'tests': ['damp', 'tps']}, 14:20:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:20:15 INFO - 'tests': ['dromaeo_dom']}, 14:20:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:20:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:20:15 INFO - 'tests': ['basic_compositor_video']}, 14:20:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:20:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:20:15 INFO - 'tests': ['a11yr', 14:20:15 INFO - 'ts_paint', 14:20:15 INFO - 'tpaint', 14:20:15 INFO - 'sessionrestore', 14:20:15 INFO - 'sessionrestore_no_auto_restore', 14:20:15 INFO - 'tabpaint']}, 14:20:15 INFO - 'other-e10s': {'tests': ['a11yr', 14:20:15 INFO - 'ts_paint', 14:20:15 INFO - 'tpaint', 14:20:15 INFO - 'sessionrestore', 14:20:15 INFO - 'sessionrestore_no_auto_restore', 14:20:15 INFO - 'tabpaint']}, 14:20:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:20:15 INFO - 'ts_paint', 14:20:15 INFO - 'tpaint', 14:20:15 INFO - 'sessionrestore', 14:20:15 INFO - 'sessionrestore_no_auto_restore', 14:20:15 INFO - 'tabpaint']}, 14:20:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:20:15 INFO - 'ts_paint', 14:20:15 INFO - 'tpaint', 14:20:15 INFO - 'sessionrestore', 14:20:15 INFO - 'sessionrestore_no_auto_restore', 14:20:15 INFO - 'tabpaint']}, 14:20:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:20:15 INFO - 'tests': ['a11yr', 14:20:15 INFO - 'ts_paint', 14:20:15 INFO - 'tpaint', 14:20:15 INFO - 'sessionrestore', 14:20:15 INFO - 'sessionrestore_no_auto_restore', 14:20:15 INFO - 'tabpaint']}, 14:20:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:20:15 INFO - 'tests': ['a11yr', 14:20:15 INFO - 'ts_paint', 14:20:15 INFO - 'tpaint', 14:20:15 INFO - 'sessionrestore', 14:20:15 INFO - 'sessionrestore_no_auto_restore', 14:20:15 INFO - 'tabpaint']}, 14:20:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:20:15 INFO - 'tests': ['tsvgx', 14:20:15 INFO - 'tsvgr_opacity', 14:20:15 INFO - 'tart', 14:20:15 INFO - 'tscrollx', 14:20:15 INFO - 'cart']}, 14:20:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:20:15 INFO - 'tsvgr_opacity', 14:20:15 INFO - 'tart', 14:20:15 INFO - 'tscrollx', 14:20:15 INFO - 'cart']}, 14:20:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:15 INFO - 'talos_options': ['--disable-e10s'], 14:20:15 INFO - 'tests': ['tp5o']}, 14:20:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:15 INFO - 'tests': ['tp5o']}, 14:20:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:15 INFO - 'talos_options': ['--disable-e10s', 14:20:15 INFO - '--xperf_path', 14:20:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:20:15 INFO - 'tests': ['tp5n']}, 14:20:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:15 INFO - 'talos_options': ['--xperf_path', 14:20:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:20:15 INFO - 'tests': ['tp5n']}}, 14:20:15 INFO - 'talos.zip': {'path': '', 14:20:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:20:15 INFO - Running post-action listener: _resource_record_post_action 14:20:15 INFO - [mozharness: 2017-01-12 22:20:15.094286Z] Finished populate-webroot step (success) 14:20:15 INFO - [mozharness: 2017-01-12 22:20:15.094395Z] Running create-virtualenv step. 14:20:15 INFO - Running pre-action listener: _resource_record_pre_action 14:20:15 INFO - Running main action method: create_virtualenv 14:20:15 INFO - Creating virtualenv /builds/slave/test/build/venv 14:20:15 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 14:20:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:20:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:20:15 INFO - Using real prefix '/tools/python27' 14:20:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:20:15 INFO - Installing distribute.............................................................................................................................................................................................done. 14:20:18 INFO - Installing pip.................done. 14:20:18 INFO - Return code: 0 14:20:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:20:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:20:19 INFO - Reading from file tmpfile_stdout 14:20:19 INFO - Output received: 14:20:19 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) 14:20:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:20: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')]} 14:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20: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')]} 14:20:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:19 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 0x1039de490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103954570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c84c07e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f2d30>, '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 0x103658db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037eab20>, '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.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 14:20:19 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 14:20:19 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 14:20:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMpvaGFxEQ/Render', 14:20:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:19 INFO - 'HOME': '/Users/cltbld', 14:20:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:19 INFO - 'LOGNAME': 'cltbld', 14:20:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:19 INFO - 'MOZ_NO_REMOTE': '1', 14:20:19 INFO - 'NO_EM_RESTART': '1', 14:20:19 INFO - 'PAGER': '/bin/cat', 14:20:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:19 INFO - 'PWD': '/builds/slave/test', 14:20:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:19 INFO - 'SHELL': '/bin/bash', 14:20:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fv5qCslE9l/Listeners', 14:20:19 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 14:20:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:19 INFO - 'USER': 'cltbld', 14:20:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:19 INFO - 'XPC_FLAGS': '0x0', 14:20:19 INFO - 'XPC_SERVICE_NAME': '0', 14:20:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:19 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 14:20:19 INFO - Cleaning up... 14:20:19 INFO - Return code: 0 14:20:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:20: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')]} 14:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20: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')]} 14:20:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:19 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 0x1039de490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103954570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c84c07e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f2d30>, '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 0x103658db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037eab20>, '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.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 14:20:19 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 14:20:19 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 14:20:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMpvaGFxEQ/Render', 14:20:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:19 INFO - 'HOME': '/Users/cltbld', 14:20:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:19 INFO - 'LOGNAME': 'cltbld', 14:20:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:19 INFO - 'MOZ_NO_REMOTE': '1', 14:20:19 INFO - 'NO_EM_RESTART': '1', 14:20:19 INFO - 'PAGER': '/bin/cat', 14:20:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:19 INFO - 'PWD': '/builds/slave/test', 14:20:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:19 INFO - 'SHELL': '/bin/bash', 14:20:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fv5qCslE9l/Listeners', 14:20:19 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 14:20:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:19 INFO - 'USER': 'cltbld', 14:20:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:19 INFO - 'XPC_FLAGS': '0x0', 14:20:19 INFO - 'XPC_SERVICE_NAME': '0', 14:20:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:19 INFO - Downloading/unpacking psutil>=3.1.1 14:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:20:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:20:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:20:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:20:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:20:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:20:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:20:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:20:24 INFO - Installing collected packages: psutil 14:20:24 INFO - Running setup.py install for psutil 14:20:24 INFO - building 'psutil._psutil_osx' extension 14:20:24 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 14:20:24 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 14:20:24 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 14:20:24 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 14:20:24 INFO - building 'psutil._psutil_posix' extension 14:20:24 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 14:20:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:20:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:20:24 INFO - ^ 14:20:24 INFO - 1 warning generated. 14:20:24 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 14:20:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:20:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:20:24 INFO - Successfully installed psutil 14:20:24 INFO - Cleaning up... 14:20:25 INFO - Return code: 0 14:20:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:20: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')]} 14:20:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20: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')]} 14:20:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1039de490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103954570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c84c07e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f2d30>, '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 0x103658db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037eab20>, '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.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 14:20: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:20: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 mozsystemmonitor==0.3 14:20:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMpvaGFxEQ/Render', 14:20:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:25 INFO - 'HOME': '/Users/cltbld', 14:20:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:25 INFO - 'LOGNAME': 'cltbld', 14:20:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:25 INFO - 'MOZ_NO_REMOTE': '1', 14:20:25 INFO - 'NO_EM_RESTART': '1', 14:20:25 INFO - 'PAGER': '/bin/cat', 14:20:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:25 INFO - 'PWD': '/builds/slave/test', 14:20:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:25 INFO - 'SHELL': '/bin/bash', 14:20:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fv5qCslE9l/Listeners', 14:20:25 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 14:20:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:25 INFO - 'USER': 'cltbld', 14:20:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:25 INFO - 'XPC_FLAGS': '0x0', 14:20:25 INFO - 'XPC_SERVICE_NAME': '0', 14:20:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:20:25 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 14:20:25 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 14:20:25 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 14:20:25 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 14:20:25 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 14:20:25 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 14:20:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:20:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:20:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:20:28 INFO - Installing collected packages: mozsystemmonitor 14:20:28 INFO - Running setup.py install for mozsystemmonitor 14:20:28 INFO - Successfully installed mozsystemmonitor 14:20:28 INFO - Cleaning up... 14:20:28 INFO - Return code: 0 14:20:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:20:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:20:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:28 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 0x1039de490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103954570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c84c07e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f2d30>, '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 0x103658db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037eab20>, '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.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 14:20:28 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 14:20:28 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 14:20:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMpvaGFxEQ/Render', 14:20:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:28 INFO - 'HOME': '/Users/cltbld', 14:20:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:28 INFO - 'LOGNAME': 'cltbld', 14:20:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:28 INFO - 'MOZ_NO_REMOTE': '1', 14:20:28 INFO - 'NO_EM_RESTART': '1', 14:20:28 INFO - 'PAGER': '/bin/cat', 14:20:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:28 INFO - 'PWD': '/builds/slave/test', 14:20:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:28 INFO - 'SHELL': '/bin/bash', 14:20:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fv5qCslE9l/Listeners', 14:20:28 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 14:20:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:28 INFO - 'USER': 'cltbld', 14:20:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:28 INFO - 'XPC_FLAGS': '0x0', 14:20:28 INFO - 'XPC_SERVICE_NAME': '0', 14:20:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:28 INFO - Downloading/unpacking jsonschema==2.5.1 14:20:28 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 14:20:28 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 14:20:28 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 14:20:28 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 14:20:28 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 14:20:28 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 14:20:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:20:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:20:31 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 14:20:31 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 14:20:31 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 14:20:31 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 14:20:31 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 14:20:31 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 14:20:31 INFO - Downloading functools32-3.2.3-2.tar.gz 14:20:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:20:31 INFO - warning: no files found matching '*.txt' 14:20:31 INFO - no previously-included directories found matching 'build' 14:20:31 INFO - no previously-included directories found matching 'dist' 14:20:31 INFO - no previously-included directories found matching '.git*' 14:20:31 INFO - Installing collected packages: jsonschema, functools32 14:20:31 INFO - Running setup.py install for functools32 14:20:31 INFO - warning: no files found matching '*.txt' 14:20:31 INFO - no previously-included directories found matching 'build' 14:20:31 INFO - no previously-included directories found matching 'dist' 14:20:31 INFO - no previously-included directories found matching '.git*' 14:20:31 INFO - Successfully installed jsonschema functools32 14:20:31 INFO - Cleaning up... 14:20:31 INFO - Return code: 0 14:20:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:20:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:20:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:20:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:31 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 0x1039de490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103954570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c84c07e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f2d30>, '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 0x103658db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037eab20>, '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.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 14:20:31 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 14:20:31 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 14:20:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMpvaGFxEQ/Render', 14:20:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:31 INFO - 'HOME': '/Users/cltbld', 14:20:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:31 INFO - 'LOGNAME': 'cltbld', 14:20:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:31 INFO - 'MOZ_NO_REMOTE': '1', 14:20:31 INFO - 'NO_EM_RESTART': '1', 14:20:31 INFO - 'PAGER': '/bin/cat', 14:20:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:31 INFO - 'PWD': '/builds/slave/test', 14:20:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:31 INFO - 'SHELL': '/bin/bash', 14:20:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fv5qCslE9l/Listeners', 14:20:31 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 14:20:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:31 INFO - 'USER': 'cltbld', 14:20:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:31 INFO - 'XPC_FLAGS': '0x0', 14:20:31 INFO - 'XPC_SERVICE_NAME': '0', 14:20:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:20:32 INFO - Cleaning up... 14:20:32 INFO - Return code: 0 14:20:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:20: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')]} 14:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20: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')]} 14:20:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1039de490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103954570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c84c07e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f2d30>, '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 0x103658db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037eab20>, '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.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 14:20:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:20:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMpvaGFxEQ/Render', 14:20:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:32 INFO - 'HOME': '/Users/cltbld', 14:20:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:32 INFO - 'LOGNAME': 'cltbld', 14:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:32 INFO - 'MOZ_NO_REMOTE': '1', 14:20:32 INFO - 'NO_EM_RESTART': '1', 14:20:32 INFO - 'PAGER': '/bin/cat', 14:20:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:32 INFO - 'PWD': '/builds/slave/test', 14:20:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:32 INFO - 'SHELL': '/bin/bash', 14:20:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fv5qCslE9l/Listeners', 14:20:32 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 14:20:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:32 INFO - 'USER': 'cltbld', 14:20:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:32 INFO - 'XPC_FLAGS': '0x0', 14:20:32 INFO - 'XPC_SERVICE_NAME': '0', 14:20:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:32 INFO - Downloading/unpacking blobuploader==1.2.4 14:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:20:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:20:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:20:34 INFO - Downloading blobuploader-1.2.4.tar.gz 14:20:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:20:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:20:35 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 14:20:35 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 14:20:35 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 14:20:35 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 14:20:35 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 14:20:35 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 14:20:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:20:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:20:35 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 14:20:35 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 14:20:35 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 14:20:35 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 14:20:35 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 14:20:35 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 14:20:36 INFO - Downloading docopt-0.6.1.tar.gz 14:20:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:20:36 INFO - Installing collected packages: blobuploader, requests, docopt 14:20:36 INFO - Running setup.py install for blobuploader 14:20:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:20:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:20:36 INFO - Running setup.py install for requests 14:20:36 INFO - Running setup.py install for docopt 14:20:37 INFO - Successfully installed blobuploader requests docopt 14:20:37 INFO - Cleaning up... 14:20:37 INFO - Return code: 0 14:20:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:20:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:37 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 0x1039de490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103954570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c84c07e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f2d30>, '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 0x103658db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037eab20>, '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.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 14:20:37 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 14:20:37 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 14:20:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMpvaGFxEQ/Render', 14:20:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:37 INFO - 'HOME': '/Users/cltbld', 14:20:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:37 INFO - 'LOGNAME': 'cltbld', 14:20:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:37 INFO - 'MOZ_NO_REMOTE': '1', 14:20:37 INFO - 'NO_EM_RESTART': '1', 14:20:37 INFO - 'PAGER': '/bin/cat', 14:20:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:37 INFO - 'PWD': '/builds/slave/test', 14:20:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:37 INFO - 'SHELL': '/bin/bash', 14:20:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fv5qCslE9l/Listeners', 14:20:37 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 14:20:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:37 INFO - 'USER': 'cltbld', 14:20:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:37 INFO - 'XPC_FLAGS': '0x0', 14:20:37 INFO - 'XPC_SERVICE_NAME': '0', 14:20:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:20:37 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-aaphxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:20:37 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-GGHI0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:20:37 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ZNkA1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:20:37 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-0MoEGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:20:37 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ZPPakP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:20:37 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-vhLVbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:20:38 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-Yl3CaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:20:38 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-WIQUCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:20:38 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-F8mWd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:20:38 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-DrA2wJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:20:38 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-TIT6Pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:20:38 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-pklWNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:20:38 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-_jHqZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:20:38 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-X_SLqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:20:38 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-Y02sYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:20:38 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-F0g99z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:20:39 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-Vr37Nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:20:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:20:39 INFO - Running setup.py install for manifestparser 14:20:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:20:39 INFO - Running setup.py install for mozcrash 14:20:39 INFO - Running setup.py install for mozdebug 14:20:39 INFO - Running setup.py install for mozdevice 14:20:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:20:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:20:39 INFO - Running setup.py install for mozfile 14:20:39 INFO - Running setup.py install for mozhttpd 14:20:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:20:40 INFO - Running setup.py install for mozinfo 14:20:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:20:40 INFO - Running setup.py install for mozInstall 14:20:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:20:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:20:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:20:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:20:40 INFO - Running setup.py install for mozleak 14:20:40 INFO - Running setup.py install for mozlog 14:20:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:20:40 INFO - Running setup.py install for moznetwork 14:20:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:20:40 INFO - Running setup.py install for mozprocess 14:20:40 INFO - Running setup.py install for mozprofile 14:20:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:20:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:20:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:20:41 INFO - Running setup.py install for mozrunner 14:20:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:20:41 INFO - Running setup.py install for mozscreenshot 14:20:41 INFO - Running setup.py install for moztest 14:20:41 INFO - Running setup.py install for mozversion 14:20:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:20:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:20:41 INFO - Cleaning up... 14:20:41 INFO - Return code: 0 14:20:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:20:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:41 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 0x1039de490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103954570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c84c07e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f2d30>, '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 0x103658db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037eab20>, '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.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 14:20:41 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 14:20:41 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 14:20:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMpvaGFxEQ/Render', 14:20:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:41 INFO - 'HOME': '/Users/cltbld', 14:20:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:41 INFO - 'LOGNAME': 'cltbld', 14:20:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:41 INFO - 'MOZ_NO_REMOTE': '1', 14:20:41 INFO - 'NO_EM_RESTART': '1', 14:20:41 INFO - 'PAGER': '/bin/cat', 14:20:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:41 INFO - 'PWD': '/builds/slave/test', 14:20:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:41 INFO - 'SHELL': '/bin/bash', 14:20:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fv5qCslE9l/Listeners', 14:20:41 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 14:20:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:41 INFO - 'USER': 'cltbld', 14:20:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:41 INFO - 'XPC_FLAGS': '0x0', 14:20:41 INFO - 'XPC_SERVICE_NAME': '0', 14:20:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:20:42 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-rMzdjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:20:42 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)) 14:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:20:42 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-cuWLo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:20:42 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)) 14:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:20:42 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-Vm4rh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:20:42 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)) 14:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:20:42 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-Pabm7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:20:42 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)) 14:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:20:42 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-8b3tNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:20:42 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)) 14:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:20:42 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-NAICpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:20:42 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)) 14:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:20:42 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-sDnv60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:20:42 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)) 14:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:20:42 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-Osb5ab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:20:42 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)) 14:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:20:42 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-6ooA9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:20:43 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)) 14:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:20:43 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-_VOmij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:20:43 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)) 14:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:20:43 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-Pg2YxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:20:43 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)) 14:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:20:43 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-yL7UBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:20:43 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)) 14:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:20:43 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-vYTL1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:20:43 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)) 14:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:20:43 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-NUc7h7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:20:43 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)) 14:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:20:43 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-EfKEro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:20:43 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)) 14:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:20:43 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-h2UHtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:20:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 14:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:20:43 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-OAhHSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:20:43 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)) 14:20:43 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)) 14:20:43 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)) 14:20:43 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)) 14:20:43 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)) 14:20:43 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)) 14:20:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:20:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:20:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:20:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:20:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:20:46 INFO - Downloading blessings-1.6.tar.gz 14:20:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:20:46 INFO - Installing collected packages: blessings 14:20:46 INFO - Running setup.py install for blessings 14:20:46 INFO - Successfully installed blessings 14:20:46 INFO - Cleaning up... 14:20:46 INFO - Return code: 0 14:20:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:20:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:20:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:20:47 INFO - Reading from file tmpfile_stdout 14:20:47 INFO - Current package versions: 14:20:47 INFO - blessings == 1.6 14:20:47 INFO - blobuploader == 1.2.4 14:20:47 INFO - docopt == 0.6.1 14:20:47 INFO - functools32 == 3.2.3-2 14:20:47 INFO - jsonschema == 2.5.1 14:20:47 INFO - manifestparser == 1.1 14:20:47 INFO - mozInstall == 1.12 14:20:47 INFO - mozcrash == 1.0 14:20:47 INFO - mozdebug == 0.1 14:20:47 INFO - mozdevice == 0.48 14:20:47 INFO - mozfile == 1.2 14:20:47 INFO - mozhttpd == 0.7 14:20:47 INFO - mozinfo == 0.9 14:20:47 INFO - mozleak == 0.1 14:20:47 INFO - mozlog == 3.4 14:20:47 INFO - moznetwork == 0.27 14:20:47 INFO - mozprocess == 0.23 14:20:47 INFO - mozprofile == 0.28 14:20:47 INFO - mozrunner == 6.13 14:20:47 INFO - mozscreenshot == 0.1 14:20:47 INFO - mozsystemmonitor == 0.3 14:20:47 INFO - moztest == 0.8 14:20:47 INFO - mozversion == 1.4 14:20:47 INFO - psutil == 3.1.1 14:20:47 INFO - requests == 1.2.3 14:20:47 INFO - wsgiref == 0.1.2 14:20:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:20:47 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')]} 14:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:47 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')]} 14:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:48 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 0x1039de490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103954570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c84c07e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f2d30>, '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 0x103658db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037eab20>, '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.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 14:20:48 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 14:20:48 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 14:20:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMpvaGFxEQ/Render', 14:20:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:48 INFO - 'HOME': '/Users/cltbld', 14:20:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:48 INFO - 'LOGNAME': 'cltbld', 14:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:48 INFO - 'MOZ_NO_REMOTE': '1', 14:20:48 INFO - 'NO_EM_RESTART': '1', 14:20:48 INFO - 'PAGER': '/bin/cat', 14:20:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:48 INFO - 'PWD': '/builds/slave/test', 14:20:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:48 INFO - 'SHELL': '/bin/bash', 14:20:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fv5qCslE9l/Listeners', 14:20:48 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 14:20:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:48 INFO - 'USER': 'cltbld', 14:20:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:48 INFO - 'XPC_FLAGS': '0x0', 14:20:48 INFO - 'XPC_SERVICE_NAME': '0', 14:20:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:48 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)) 14:20:48 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)) 14:20:48 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)) 14:20:48 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)) 14:20:48 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)) 14:20:48 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)) 14:20:48 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)) 14:20:48 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)) 14:20:48 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)) 14:20:48 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)) 14:20:48 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)) 14:20:48 INFO - Cleaning up... 14:20:48 INFO - Return code: 0 14:20:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:20:48 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')]} 14:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:48 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')]} 14:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:49 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 0x1039de490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103954570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c84c07e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f2d30>, '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 0x103658db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037eab20>, '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.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 14:20:49 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 14:20:49 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 14:20:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMpvaGFxEQ/Render', 14:20:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:49 INFO - 'HOME': '/Users/cltbld', 14:20:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:49 INFO - 'LOGNAME': 'cltbld', 14:20:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:49 INFO - 'MOZ_NO_REMOTE': '1', 14:20:49 INFO - 'NO_EM_RESTART': '1', 14:20:49 INFO - 'PAGER': '/bin/cat', 14:20:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:49 INFO - 'PWD': '/builds/slave/test', 14:20:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:49 INFO - 'SHELL': '/bin/bash', 14:20:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fv5qCslE9l/Listeners', 14:20:49 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 14:20:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:49 INFO - 'USER': 'cltbld', 14:20:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:49 INFO - 'XPC_FLAGS': '0x0', 14:20:49 INFO - 'XPC_SERVICE_NAME': '0', 14:20:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:20:49 INFO - Cleaning up... 14:20:49 INFO - Return code: 0 14:20:49 INFO - Running post-action listener: _resource_record_post_action 14:20:49 INFO - Running post-action listener: _start_resource_monitoring 14:20:49 INFO - Starting resource monitoring. 14:20:49 INFO - [mozharness: 2017-01-12 22:20:49.656168Z] Finished create-virtualenv step (success) 14:20:49 INFO - [mozharness: 2017-01-12 22:20:49.657151Z] Running install step. 14:20:49 INFO - Running pre-action listener: _resource_record_pre_action 14:20:49 INFO - Running main action method: install 14:20:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:20:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:20:49 INFO - Reading from file tmpfile_stdout 14:20:49 INFO - Detecting whether we're running mozinstall >=1.0... 14:20:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:20:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:20:50 INFO - Reading from file tmpfile_stdout 14:20:50 INFO - Output received: 14:20:50 INFO - Usage: mozinstall [options] installer 14:20:50 INFO - Options: 14:20:50 INFO - -h, --help show this help message and exit 14:20:50 INFO - -d DEST, --destination=DEST 14:20:50 INFO - Directory to install application into. [default: 14:20:50 INFO - "/builds/slave/test"] 14:20:50 INFO - --app=APP Application being installed. [default: firefox] 14:20:50 INFO - mkdir: /builds/slave/test/build/application 14:20:50 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'] 14:20:50 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 14:21:21 INFO - Reading from file tmpfile_stdout 14:21:21 INFO - Output received: 14:21:21 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:21:21 INFO - Running post-action listener: _resource_record_post_action 14:21:21 INFO - [mozharness: 2017-01-12 22:21:21.366593Z] Finished install step (success) 14:21:21 INFO - [mozharness: 2017-01-12 22:21:21.366767Z] Running run-tests step. 14:21:21 INFO - Running pre-action listener: _resource_record_pre_action 14:21:21 INFO - Running main action method: run_tests 14:21:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:21:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:21:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:21:21 INFO - 2017-01-12 14:21:21.381 screenresolution[1896:15818] starting screenresolution argv=screenresolution get 14:21:21 INFO - 2017-01-12 14:21:21.383 screenresolution[1896:15818] Display 0: 1600x1200x32@60 14:21:21 INFO - 2017-01-12 14:21:21.389 screenresolution[1897:15820] starting screenresolution argv=screenresolution list 14:21:21 INFO - Available Modes on Display 0 14:21:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:21:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:21:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:21:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:21:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:21:21 INFO - Intel Iris: 14:21:21 INFO - Chipset Model: Intel Iris 14:21:21 INFO - Type: GPU 14:21:21 INFO - Bus: Built-In 14:21:21 INFO - VRAM (Dynamic, Max): 1536 MB 14:21:21 INFO - Vendor: Intel (0x8086) 14:21:21 INFO - Device ID: 0x0a2e 14:21:21 INFO - Revision ID: 0x0009 14:21:21 INFO - Displays: 14:21:21 INFO - Display: 14:21:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:21:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:21:21 INFO - Display Serial Number: 1600x1200 60 14:21:21 INFO - Main Display: Yes 14:21:21 INFO - Mirror: Off 14:21:21 INFO - Online: Yes 14:21:21 INFO - Rotation: Supported 14:21:21 INFO - Return code: 0 14:21:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:21:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:21:21 INFO - Python 2.7.3 14:21:21 INFO - Return code: 0 14:21:21 INFO - grabbing minidump binary from tooltool 14:21:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:21 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 0x1037f2d30>, '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 0x103658db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037eab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:21:21 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 14:21:21 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 14:21:21 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 14:21:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:21:21 INFO - Return code: 0 14:21:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:21:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:21:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:21:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:21:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:21:21 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/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--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 14:21:21 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/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0096 --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 14:21:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qMpvaGFxEQ/Render', 14:21:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:21 INFO - 'HOME': '/Users/cltbld', 14:21:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:21 INFO - 'LOGNAME': 'cltbld', 14:21:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:21:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:21:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:21 INFO - 'MOZ_NO_REMOTE': '1', 14:21:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:21:21 INFO - 'NO_EM_RESTART': '1', 14:21:21 INFO - 'PAGER': '/bin/cat', 14:21:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:21 INFO - 'PWD': '/builds/slave/test', 14:21:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:21:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:21 INFO - 'SHELL': '/bin/bash', 14:21:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fv5qCslE9l/Listeners', 14:21:21 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 14:21:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:21 INFO - 'USER': 'cltbld', 14:21:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:21 INFO - 'XPC_FLAGS': '0x0', 14:21:21 INFO - 'XPC_SERVICE_NAME': '0', 14:21:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:21 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/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--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 14:21:21 INFO - mozversion application_buildid: 20170112121216 14:21:21 INFO - mozversion application_changeset: 8d0ece73d3278ee81807f7d5dcdd402f994954dc 14:21:21 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:21:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:21:21 INFO - mozversion application_name: Firefox 14:21:21 INFO - mozversion application_remotingname: firefox-dev 14:21:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:21:21 INFO - mozversion application_vendor: Mozilla 14:21:21 INFO - mozversion application_version: 52.0a2 14:21:21 INFO - mozversion platform_buildid: 20170112121216 14:21:21 INFO - mozversion platform_changeset: 8d0ece73d3278ee81807f7d5dcdd402f994954dc 14:21:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:21:21 INFO - mozversion platform_version: 52.0a2 14:21:21 INFO - using testdate: 1484259681 14:21:21 INFO - actual date: 1484259681 14:21:21 INFO - starting webserver on 'localhost:53537' 14:21:21 INFO - SUITE-START | Running 2 tests 14:21:21 INFO - TEST-START | dromaeo_css 14:21:21 INFO - operating with platform_type : mac_ 14:21:21 INFO - Initialising browser for dromaeo_css test... 14:21:21 INFO - TEST-INFO | started process 1907 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpkSrytY/profile http://localhost:53537/getInfo.html) 14:21:24 INFO - PROCESS | 1907 | __metrics Screen width/height:1600/1200 14:21:24 INFO - PROCESS | 1907 | colorDepth:24 14:21:24 INFO - PROCESS | 1907 | Browser inner width/height: 1024/689 14:21:24 INFO - PROCESS | 1907 | __metrics 14:21:24 INFO - PROCESS | 1907 | 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" 14:21:24 INFO - PROCESS | 1907 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:21:25 INFO - TEST-INFO | 1907: exit 0 14:21:25 INFO - Browser initialized. 14:21:25 INFO - Running cycle 1/1 for dromaeo_css test... 14:21:25 INFO - TEST-INFO | started process 1909 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpkSrytY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:21:37 INFO - PROCESS | 1909 | RSS: Main: 222633984 14:21:37 INFO - PROCESS | 1909 | 14:24:41 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53537/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53537/tests/dromaeo/cssquery-ext.html) 14:24:42 INFO - PROCESS | 1909 | RSS: Main: 360419328 14:24:42 INFO - PROCESS | 1909 | 14:27:45 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53537/tests/dromaeo/cssquery-ext.html (next: http://localhost:53537/tests/dromaeo/cssquery-jquery.html) 14:27:46 INFO - PROCESS | 1909 | RSS: Main: 347738112 14:27:46 INFO - PROCESS | 1909 | 14:30:48 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53537/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53537/tests/dromaeo/cssquery-mootools.html) 14:30:49 INFO - PROCESS | 1909 | RSS: Main: 320557056 14:30:49 INFO - PROCESS | 1909 | 14:33:51 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53537/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53537/tests/dromaeo/cssquery-prototype.html) 14:33:52 INFO - PROCESS | 1909 | RSS: Main: 315097088 14:33:52 INFO - PROCESS | 1909 | 14:36:55 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53537/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53537/tests/dromaeo/cssquery-yui.html) 14:36:56 INFO - PROCESS | 1909 | RSS: Main: 306434048 14:36:56 INFO - PROCESS | 1909 | 14:39:59 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53537/tests/dromaeo/cssquery-yui.html (next: http://localhost:53537/tests/dromaeo/cssquery-dojo.html) 14:39:59 INFO - PROCESS | 1909 | RSS: Main: 434872320 14:39:59 INFO - PROCESS | 1909 | 14:39:59 INFO - PROCESS | 1909 | __start_tp_report 14:39:59 INFO - PROCESS | 1909 | _x_x_mozilla_page_load 14:39:59 INFO - PROCESS | 1909 | _x_x_mozilla_page_load_details 14:39:59 INFO - PROCESS | 1909 | |i|pagename|runs| 14:39:59 INFO - PROCESS | 1909 | |0;dojo.html;2748.4365705030464;3255.3977514159883;3266.5753452051213;3278.9508157377645;3291.814946619218;5453.700046497441;5484.2596249506305;5498.0653288940875;5503.779848806048;5513.834584962453;5455.699936503491;5486.231927530149;5498.642588231762;5504.064309067458;5523.861903452405;4983.576396006344;5011.473795251501;5074.873128171788;5116.437191908887;5117.104506711329;5005.399352077756;5009.799608015694;5017.172166592508;5042.672226305297;5119.948800512003;5044.646874718771;5074.112030394685;5080.466551012146;5089.3638295213095;5098;5074.949250507485;5093.694378337274;5105.463926287724;5119.948800511985;5162.199859021857;4833.178359678863;5055.620828437882;5070.645054846163;5073;5087.669301495391;5348.839534813923;5403.756830942578;5404.405515393303;5460.099083651193;5460.31746031746;5900.262467191601;5904.055351143839;5945.16767652529;5975.1037344398255;5977.013792724195;6089.908651370233;6090.299615544225;6097.146399504073;6099.603525770811;6129.816105516842;5411.431799661058;5469.72651367434;5504.2018907258225;5554.944450555524;5579.414161513064;6166.167567378437;6198.876022479615;6205.689715514297;6206.10011548332;6208.379162083846;5829;5834.591578589543;5850.063989761703;5860;5874.441928016831;5855.473007429352;5886.735096920649;5898.616589921641;5906.970465147732;5943.375945525658;5836.066229403275;5854.034084376115;5884.205632239678;5888.735006924613;5936.614120082181;5914.4381283777975;5935.287765468085;5944.881102377928;5947;5956.076808094752;4684.016356565159;4692.6245900327895;4749.453812811502;4756.1438940990265;4786.353842231324;4243.554426785205;4252.21334053195;4294.9785251073545;4332.220200363901;4342.82628694855;5462.863428414242;5525.834224973257;5532.059549876451;5557.610967232255;5561.555075593943;6006.399360064046;6017.488513476316;6022.638641681513;6027.065804800263;6031.577789554689;5435.972820135953;5451.672899626035;5492.835214943558;5534.7786088556;5535.806246781343;4354.651627869763;5156.767945442464;5190.429052804188;5219.686818790885;5243.659162154448;6218.9378106218355;6241.251049874044;6248.125262463259;6252.468540173954;6263.530235232284;5851.941480585139;5879.794207202727;5898.616589921641;6039.486643635164;6050.45545900871;6053.182820319201;6128.50971922254;6135.538906164888;6157.0456579230295;6166.229221347332;5881.117832325185;5921.1414344918885;5929.407059294036;5929.792457263975;5965.463108320272;6029.3669164737075;6043.879122417615;6057.273127224761;6060;6069.2110025696375;5652.88694226105;5704.0588302929555;5730.054541000688;5751.8562035949435;5815.563832712648;5710.771569137354;5768.769249229984;5786.247787787729;5799.942000580109;5819.272590926134;5540.889182216414;5621.943780562142;5656.915146272891;5673.262475878109;5684.602077854676;5628.662280263197;5646.9152962704775;5790.739416726173;5791.189233507396;5817.95276850171;5628.831135065954;5631.183478395692;5668.979583674978;5733.111367738007;5735.197072409783 14:39:59 INFO - PROCESS | 1909 | |1;ext.html;459.84365315792644;475.4508542633259;501.92972983782255;514.2491961735866;515.9948400515993;29283;30851;37067.25865482689;38191.23617527655;38336;39391.40912886309;39551.20897582047;39761.602383976155;39870.003249918766;39948.80025599868;8977.91022089779;9164.221041211506;9166.633334666605;9169.770755731093;9172.357934944557;8359.247667709906;8560.0583935767;8569.400141990063;8659.220670139686;9244.352895297332;8531.018932822742;11049.116070714364;11292.096632269398;11297.830532542019;11485.770284594302;11153.442327883615;11201.66395008151;11208.103351731923;11214.439278036107;11230.55077796887;11074.169437292156;11146.554137834477;11164.274322169114;11165.83251251232;11171.720706982309;37921.431178532344;38159.80920095409;38162.61837381646;38177;38226.61773382286;3213.8714451422147;3234.3531293741344;3274.3778682050574;3398.2863598644312;3514.9982255057184;27923.58114628282;27969.881204751786;28240.576391354145;28631;28815;3162.36752649471;3207.0975766930965;3217.3726123405713;3232.272738633812;3278.688524590184;412863;415429;416028;416565.999999997;424842;32400.67599324024;32948.341033179204;33252.50121248183;34126.65873341235;58117.70941145267;34324;34330.65669343275;34345.65654343425;34387.4841877372;34414.13964650904;14118.435262589586;14258.429662813578;14270.785938210935;14271.714565708628;14280.42878284857;12079.456424460921;12186.756264874653;12199.39003049844;12203.816942745865;12282.201656892274;6598.47212223021;7226.205117437078;7401.259874012556;7733;7808.960955195302;3201.7598680099086;3280.950785738216;3287.1453422109944;3306;3314.767966242388;875.868619707049;944.4710961861242;969.4425705219386;1048.3814549415729;1082.6643740440488;24009.720534629334;28057.43885122286;28802.84788608432;28979.710202897702;29180.85409572981;1021.7701017271203;1042.7914417116608;1059.1209296284067;1079.1474734959452;1142.5258517715056;11610.6516804496;15058.397664093533;15084.547463576111;15153.469628562947;15408.61478463025;541621;624910.8754456198;630811;632502.8374858188;632714;47538;48323.27515087277;54122;54296.18555721588;55587.72206138943;1333.8999575031912;1352.1684164238923;1374.5326588959801;1377.9104358216684;1378.2144177818548;28795.704193310932;29382.41235175327;29720.256993575334;30330.241743955696;30417.84791076119;47503.52496474991;47992.04015919732;48035.27947080727;48122.5187748132;48176.27735584039;652634;672192;675800;822576;823874;37973.81013094917;38388;38553.843384698506;39193;39937.600623993385;42993.3550996729;43289.91775205645;43734.781326093165;43880.56119438765;43984.34023489714;31970.52044219385;35879.46180807238;36143.73496927595;36518.26963460769;36518.90443286706;35508.934731958085;36789.44815827818;36831.63168368282;36876;37408;36542;37273.88178354654;37448.62551374451;37490.437643435915;37506.87479375623;31495.055148345586;33359.66640333566;33406.164845879044;33604.99185024453;33664.3267134651;24790.884410201947;25124.497510050063;25364.475155775624;25386.492270154125;25525.489490209722 14:39:59 INFO - PROCESS | 1909 | |2;jquery.html;288.3411404939713;355.58219092566236;368.2800125754149;375.26635427738773;396.6588733689027;14310.642233944169;14591.416343346267;15299.694006119886;15534.22328883557;18905.7164142538;16243.51269461918;19516.926569038696;19818.20727170915;19882.204711811548;19970.600587988232;5655.26481557397;6033.426824451692;6043.939560604382;6048.0625503047095;6048.334683184846;5738.110592858093;5860.560457965648;5911.88176236475;5914.645121292715;5977.88044239115;5894.263217097863;5898.233229680157;5905.379935106816;5909.054551271797;5909.911351329733;5639.182318563806;5826.1552074948895;5874.441928016831;5877.970610146944;5896.528277737771;5153.9742301288625;5157.149070403379;5159.1229490986625;5161.845144645667;5164.845054648366;24076.39809004789;24514.877425612936;24719.134830281033;24737.505249895083;25009.249722508353;2598.5322641924636;2662.3211081174177;2724.182745176459;3082.1370016395445;3127.0149902780777;13644.590662280048;15037.924810375987;15470;15527.611809704735;15538.689226215412;1780.6883795335893;1788.6333301673126;1809.6471188118192;1834.4038187589088;1860.4139695995718;107037.46481267543;107231.46384268107;107826;108036.4598177012;108769;8282.834343313221;8723.255348930239;8898.4660920345;9379.343445958717;9497.477638729799;12269.386530673431;12361.505539778487;12367.75264494723;12404.75190496185;12408;7760.805979850436;7985.68057277714;7994.960025199837;8065.556394398247;8069.515829050276;7973.401994850409;8034.317083047889;8297.211764882326;8468.745937621881;8494.490330580185;3865;3873.457715919717;3879.3017256893468;3938.369860822255;3945.9605403945593;1888.2824526679774;1896.8387687046757;1900.5913728548428;1900.6008738164865;1908.2367053178561;811.6591031766673;868.3227082875368;911.7128104647015;921.9262459003397;928.166870430907;19619.411417657495;20721.68917466239;20744.58510829805;20745.481362966046;20853.582928341653;886.2865393358409;900.9594568244445;922.4834092907993;928.6471140966524;931.4830269200523;9712.805743885225;10302.484875756183;10901.1279097672;10948.890511094947;10954.123670106372;499989;598874;618653;623341;633336;124406;126937.73062269259;131748;135486;135598;873.2359185712502;965.0879918476971;987.5605355616682;990.9157721593601;1012.4684540616088;18203.271869125616;18557.886526807895;19038.428847134608;19079.809201907803;19099.713504297724;49886;51949.8830070197;52023;52530.47469525409;52841.735791320796;69967.65016174887;70668.64665676639;73455;74245;74541.62729186319;6750.729970801113;7377.557346559223;7691.230876912264;7845.29392354668;8172.509649421054;8997.460152390877;12673.493060277486;12686.746265074831;12688.17526860751;13014.804777928528;10770.353778773298;10791.73020674458;10823.56705731762;11154.274972126786;11382.430878455878;9286.34995550305;10742.785144296913;10865.565377384815;10871.67384978452;11042.503087360927;9222.769430764285;10767.300125491818;10892.237543372205;11098.556057757922;11521.711957201138;7318.6706598202145;7361.558306501627;7438.62806859644;7507.88738168917;7519.097708275042;9075.863862041942;10008.64969726056;10033.19734421259;10317.329373590692;10927.836082458929 14:39:59 INFO - PROCESS | 1909 | |3;mootools.html;1300.304337179609;1443.3216388297499;1467.5597320803759;1610.983890161098;1815.5824160443099;7297.197308296083;7684.538927664337;7898.763037108896;8183.795405114875;8184.345252379795;8001.519908805476;8120.309773669245;8122.1877812218745;8126.593670316475;8130.308923741489;2260.9321720348416;2292.4612716011725;2293.243229734192;2298.402415371999;2298.7586303438147;2269.523400085984;2312.0867257433306;2327.359976006603;2328.3480625424822;2330.0796185506715;2240.675102110193;2266.580682573729;2299.1378233162563;2305.101010605865;2306.08909480755;1905.504568812112;2226.6640015990356;2235.441139715071;2265.67147763575;2266.331432227497;2272.1593010586025;2288.290629904735;2293.472501324688;2298.321995011476;2300.562893050315;7916.881246781303;8002.439829211958;8011.319037881787;8033.236842499953;8117.715879944174;4850.53919877611;5046.621503387224;5070.29015937769;5094.439611642716;5096.719680417576;2784.665840099181;3184.4268031754277;3364.243045314809;3429.176997520602;3438.9828050859837;998.7952469743685;1004.206676725386;1021.3463383434607;1031.6079889641962;1044.4307852220559;10162.339447935861;10294.794104117876;10297.485125743682;10309.484525773682;10345.844812327821;4132.607402296777;4203.390508376268;4312.202242585132;4313.029568347097;4314.22343978087;8377.664893404195;8378.162183781573;8393.748187554384;8397.832043359098;8408.747737567883;4102.2410853992105;4898.730569818623;4906.681065730716;4925.482824303468;4928.433230178503;3910.980445097813;4121.814518346681;4268.466441694788;4272.764997925082;4606.032733126014;3536.3634545781492;3562.590302115238;3636.636336366342;3640.9453858192146;3644.927101457956;1075.5858994287255;1082.9945850270856;1092.579356947581;1108.4402376799826;1109.8113320735495;679.3138929681057;872.6814712629971;883.3286702106445;905.2215095018279;909.6907051602487;5319.122344813062;5389.299391079135;5438.510534051875;5477.507024367826;5584.944150558524;402.6295807856779;419.3374468340015;423.18325631530826;424.90227247733196;429.8973133911507;3720.069982504374;3727.0496023514374;3731.9253614928093;3770.962290377061;3818.4463252828186;9469.952650236704;9746.707598772047;9844.261680374;9851.359661621971;9861.9644937282;9785.853212201822;9792.510374481248;9801.068898454636;9803.754906127262;9809.019098090133;7521.7367392142105;7629.198934111839;7630.923690763132;7642.808929776689;7763.029621297338;5183.429822719422;5319.601029922694;5423.2407462954425;5509.559235261089;5561.63849349791;9028.058967322057;9059.320550958573;9155.862662060208;9161.129692679186;9172.403793753385;8313.08556058821;8844.115588441104;9055.68305109318;9154.26765858743;9183.724488265363;5536.833894983157;6486.772962946463;6668.533202675766;6907.723691052302;6976.860462790614;7655.617219139132;7659.502132361378;7679.539227646359;7748.070231572022;7795.298423141718;6962.164540255202;6982.1272340957385;6997.860042799014;7010.088688470466;7022.964885175746;6816.7614133505085;6890.069840571659;6893.965530172317;6928.445724342141;6946.826329341605;6380.968095159495;6516.609003459807;6518.217813862367;6526.379993900572;6535.085088087577;4556;4638.095571363552;4642.907141857211;4702.858914232578;4763.404574428197;4715.976420117878;4762.214234651382;4764.213904705684;4883.974365383309;4924.8768780780765 14:39:59 INFO - PROCESS | 1909 | |4;prototype.html;285.1417234125283;295.5669943532725;302.4435039527269;309.7676742443166;312.1089290076832;4003.719739618224;4991.126552853254;5117.59059275258;5143.228515722643;5143.485651434859;4167.520735115462;4508.97745511272;4700.438261147674;5038.143515602347;5043.823466178685;3047.603811504501;3871.3999330103807;3900.4344370066324;3924;3971.4185779243435;3443.7933723976516;3865.93686736147;3912.628300311466;3931.5871833457504;3976.13718122951;3901.648851603355;3902.63407807268;3918.196769662227;3924.2543916655886;3934.960650393488;3756.8872933811895;3872.9031774205587;3913.236918800835;3929.469521614574;3941.31027070263;3424.400729872262;3428.468587368962;3432.828358582061;3434.227298857762;3439.4152993991083;4900.142475066885;4912.606991440676;4925.014997000614;4947.975260123676;4974.278729584226;4240;4345.760983145926;4454.175977444151;4477.306017567283;4500.504944456107;4349.826006959718;4686.507916668735;5257.631965762398;5272.103742363769;5303.011370735974;3159.961824623128;3745.5879853216125;3751.0307088778336;3751.456038874348;3752.887413377603;4482;5510.779568817243;5540.085885828834;5554.77780888768;5604.010576245415;3288.523164141186;3605.675489205984;3802.6767724743154;3821.923561528754;3828.9413221984787;3428.5714285714284;3854.5374555053572;3863.517060367454;3878.5539662939123;3905.6484916357663;3713.2573485303046;4418.690691651618;4421.911561768747;4427.358033085185;4446.3330500424545;4287.592678695519;4606.355110284563;5261.842144735664;5303.310569626001;5363.597730170253;1028.711960651019;1104.8012905997084;1145.8510393648771;1177.717347836513;1235.4063258396568;774.0633833062058;777.8988731464987;809.6599428240153;826.5867066466767;833.8957630296213;3915.686745060388;4604.976975115103;4891.31521586971;5058.721770302669;5143.897122057613;4545.795139495953;4983;5180.119379705459;5450.073487507133;5457.189547383101;4209.894752631209;4457.286834106528;4779.828941909254;5011.674241174312;5012.674176178537;2404.927852164438;2569.331973686855;2703.864806759654;2704.905328313539;2713.8778754956074;5102.464241254689;5120.359955005624;5236.612297741101;5308.840734777963;5320.654157479828;4486.551344865553;5031.320771695774;5164.746209887747;5274.604404669665;5327.111208817508;4434.445694288214;5298;5328.280682107943;5328.8401347959625;5348.5988550858065;4462.754548499864;4544.840930567414;4832.516748325139;5052.747362631927;5138.845834624967;3961.7028722845325;4262.936055959101;4765.26138448541;4808.639352048541;4906.589920005562;4710.599599033983;5042.596592272536;5110.962339751703;5305.283786688902;5364.651297665795;2950.5869178315484;3435.656434356544;3444.432783083641;3471.5486986691567;3475.58293004841;4730.526947305242;5121.8719532012;5128.256382835165;5146.536811686966;5179.4561571036;4198.8243291878325;4803.711777293373;4966.602671786321;5008.620905070063;5009.524095210949;3878.3406820841087;4054.79726013704;4779.904401911873;4984.211998880949;5011.546651471178;4371.978140109279;4394.450693663292;4885.193942999365;4902.8774280643265;4924.359833221658;4794.736289504111;4986.1329735403015;5006.474320196473;5086.262491938574;5418.832467013226;4006.6594339480303;4400.515943246304;5280.419153893145;5284.180951952453;5300.236831849276 14:39:59 INFO - PROCESS | 1909 | |5;yui.html;688.5799662206055;693.0782059860386;699.1889408286389;719.5071376107367;751.0911796725961;14070.296485175752;14655.926720366384;14661.047031942917;14687.70624587509;14725.969182157254;12997.220166789975;13017.934910325435;13045.152065115784;13126.146800457987;13137;647.2427260105676;669.6517810738413;675.2774531251563;795.7453614843223;824.6165533027141;808.8624933761246;816.8325493273865;823.3742355809568;823.4853216739538;824.0399934076823;870.7953630896734;874.715717391847;875.4747151708956;875.5622188905547;876.7720392697912;677.552815142004;695.58265040975;819.3404309530825;865.4720620421538;869.0049892872657;637.8660481298933;641.1888960465016;641.1888960465016;643.4562794438692;647.1296106736479;14227.715445691028;14366.856331436657;14368.856311436857;14380;14405.423783048771;13598.980076494303;13814.447422103103;13829.10110842792;13832;13896.791548126786;10963.671089867317;11390.03184729299;11413.79481824054;11560.30638161713;11904.285742855456;3578.1233597768455;3669.7064234861145;3690;3711.109333759905;3788.867389641377;72036;75134.62432687856;75157;86810;92698.53650731771;10430.426326552113;10707.625233116803;10813.243072984973;10851.077658399125;10935.398553079656;20849.791502084787;21050;21110.261140859995;21181.78818211798;21370.145194192057;7753.883691744629;7766.106897706723;7770.067591889009;7836.294733474015;7903.752846816562;4163.770992595376;4222.873313800591;4290;4360.018995725936;4426.181156485996;3295.3574053059424;3374.865005399806;3418.1454636340914;3421.3328400962073;3426.417509023472;613.2671457608116;616.2358675242667;624.8594066335129;628.6887990444668;631.62733986947;570.5036618142243;639.0765344077856;641.2913730327921;650.2392201124601;685.9657017149123;8688.391812573185;8812.295016398673;8812.735617931472;8877.023527411979;9098.226650734628;651.3006125208859;661.8510835062073;663.4559661077966;667.9632620205936;669.1334721535623;4083.9183216335505;4355.542668019875;4384.544654993214;4386.43286982792;4495.190865644216;69719;96739.51630241804;109046;110950;114679;62806;62817.057744133876;63539;71254.64372678104;75821.62089189519;77821.61089194518;83461.58269208494;84409.73385399223;85236.14763852282;85829;8389.993200815697;8395.832083358177;8477.364197685074;8492.490450572739;8758.343124265579;47303;47823.76088119537;48307.03385932332;48677.02645947132;50245;47965;57859;67576;69789;70834;11518.424078796193;11696.239744416585;11705.824412633647;11815.113866459878;12036.819447708467;11575.189736718687;11724.237924535219;11738.647840564796;11896.405179741152;12634.810477842655;11524.539018439504;11790.587329443428;11955.521779129085;12047.457864396298;12115.939420302842;10886.29239099456;11993.1005174614;12090.395480225778;12224.266544007389;12233.816492752794;10768.3538987661;11189.272697274651;11429.314241145557;11433.485493152995;11500.722294146128;9051.818963620823;9344.252459803063;9391.530423478935;9516.762080948032;9999.950000249952;9199.494027828534;9509.572069257038;9816.950915245377;9835.114839664464;9859.35914165577 14:39:59 INFO - PROCESS | 1909 | __end_tp_report 14:39:59 INFO - PROCESS | 1909 | __start_cc_report 14:39:59 INFO - PROCESS | 1909 | _x_x_mozilla_cycle_collect,1576 14:39:59 INFO - PROCESS | 1909 | __end_cc_report 14:39:59 INFO - PROCESS | 1909 | __startTimestamp1484260799366__endTimestamp 14:39:59 INFO - PROCESS | 1909 | 14:39:59 INFO - PROCESS | 1909 | ------- Summary: start ------- 14:39:59 INFO - PROCESS | 1909 | Number of tests: 6 14:39:59 INFO - PROCESS | 1909 | 14:39:59 INFO - PROCESS | 1909 | [#0] dojo.html Cycles:165 Average:5519.45 Median:5656.92 stddev:621.58 (11.0%) stddev-sans-first:584.24 14:39:59 INFO - PROCESS | 1909 | Values: 2748.4 3255.4 3266.6 3279.0 3291.8 5453.7 5484.3 5498.1 5503.8 5513.8 5455.7 5486.2 5498.6 5504.1 5523.9 4983.6 5011.5 5074.9 5116.4 5117.1 5005.4 5009.8 5017.2 5042.7 5119.9 5044.6 5074.1 5080.5 5089.4 5098.0 5074.9 5093.7 5105.5 5119.9 5162.2 4833.2 5055.6 5070.6 5073.0 5087.7 5348.8 5403.8 5404.4 5460.1 5460.3 5900.3 5904.1 5945.2 5975.1 5977.0 6089.9 6090.3 6097.1 6099.6 6129.8 5411.4 5469.7 5504.2 5554.9 5579.4 6166.2 6198.9 6205.7 6206.1 6208.4 5829.0 5834.6 5850.1 5860.0 5874.4 5855.5 5886.7 5898.6 5907.0 5943.4 5836.1 5854.0 5884.2 5888.7 5936.6 5914.4 5935.3 5944.9 5947.0 5956.1 4684.0 4692.6 4749.5 4756.1 4786.4 4243.6 4252.2 4295.0 4332.2 4342.8 5462.9 5525.8 5532.1 5557.6 5561.6 6006.4 6017.5 6022.6 6027.1 6031.6 5436.0 5451.7 5492.8 5534.8 5535.8 4354.7 5156.8 5190.4 5219.7 5243.7 6218.9 6241.3 6248.1 6252.5 6263.5 5851.9 5879.8 5898.6 6039.5 6050.5 6053.2 6128.5 6135.5 6157.0 6166.2 5881.1 5921.1 5929.4 5929.8 5965.5 6029.4 6043.9 6057.3 6060.0 6069.2 5652.9 5704.1 5730.1 5751.9 5815.6 5710.8 5768.8 5786.2 5799.9 5819.3 5540.9 5621.9 5656.9 5673.3 5684.6 5628.7 5646.9 5790.7 5791.2 5818.0 5628.8 5631.2 5669.0 5733.1 5735.2 14:39:59 INFO - PROCESS | 1909 | 14:39:59 INFO - PROCESS | 1909 | [#1] ext.html Cycles:180 Average:69247.57 Median:34400.81 stddev:162090.40 (471.2%) stddev-sans-first:162462.82 14:39:59 INFO - PROCESS | 1909 | Values: 459.8 475.5 501.9 514.2 516.0 29283.0 30851.0 37067.3 38191.2 38336.0 39391.4 39551.2 39761.6 39870.0 39948.8 8977.9 9164.2 9166.6 9169.8 9172.4 8359.2 8560.1 8569.4 8659.2 9244.4 8531.0 11049.1 11292.1 11297.8 11485.8 11153.4 11201.7 11208.1 11214.4 11230.6 11074.2 11146.6 11164.3 11165.8 11171.7 37921.4 38159.8 38162.6 38177.0 38226.6 3213.9 3234.4 3274.4 3398.3 3515.0 27923.6 27969.9 28240.6 28631.0 28815.0 3162.4 3207.1 3217.4 3232.3 3278.7 412863.0 415429.0 416028.0 416566.0 424842.0 32400.7 32948.3 33252.5 34126.7 58117.7 34324.0 34330.7 34345.7 34387.5 34414.1 14118.4 14258.4 14270.8 14271.7 14280.4 12079.5 12186.8 12199.4 12203.8 12282.2 6598.5 7226.2 7401.3 7733.0 7809.0 3201.8 3281.0 3287.1 3306.0 3314.8 875.9 944.5 969.4 1048.4 1082.7 24009.7 28057.4 28802.8 28979.7 29180.9 1021.8 1042.8 1059.1 1079.1 1142.5 11610.7 15058.4 15084.5 15153.5 15408.6 541621.0 624910.9 630811.0 632502.8 632714.0 47538.0 48323.3 54122.0 54296.2 55587.7 1333.9 1352.2 1374.5 1377.9 1378.2 28795.7 29382.4 29720.3 30330.2 30417.8 47503.5 47992.0 48035.3 48122.5 48176.3 652634.0 672192.0 675800.0 822576.0 823874.0 37973.8 38388.0 38553.8 39193.0 39937.6 42993.4 43289.9 43734.8 43880.6 43984.3 31970.5 35879.5 36143.7 36518.3 36518.9 35508.9 36789.4 36831.6 36876.0 37408.0 36542.0 37273.9 37448.6 37490.4 37506.9 31495.1 33359.7 33406.2 33605.0 33664.3 24790.9 25124.5 25364.5 25386.5 25525.5 14:39:59 INFO - PROCESS | 1909 | 14:39:59 INFO - PROCESS | 1909 | [#2] jquery.html Cycles:180 Average:34206.32 Median:3869.23 stddev:99497.19 (2571.5%) stddev-sans-first:99743.71 14:39:59 INFO - PROCESS | 1909 | Values: 288.3 355.6 368.3 375.3 396.7 14310.6 14591.4 15299.7 15534.2 18905.7 16243.5 19516.9 19818.2 19882.2 19970.6 5655.3 6033.4 6043.9 6048.1 6048.3 5738.1 5860.6 5911.9 5914.6 5977.9 5894.3 5898.2 5905.4 5909.1 5909.9 5639.2 5826.2 5874.4 5878.0 5896.5 5154.0 5157.1 5159.1 5161.8 5164.8 24076.4 24514.9 24719.1 24737.5 25009.2 2598.5 2662.3 2724.2 3082.1 3127.0 13644.6 15037.9 15470.0 15527.6 15538.7 1780.7 1788.6 1809.6 1834.4 1860.4 107037.5 107231.5 107826.0 108036.5 108769.0 8282.8 8723.3 8898.5 9379.3 9497.5 12269.4 12361.5 12367.8 12404.8 12408.0 7760.8 7985.7 7995.0 8065.6 8069.5 7973.4 8034.3 8297.2 8468.7 8494.5 3865.0 3873.5 3879.3 3938.4 3946.0 1888.3 1896.8 1900.6 1900.6 1908.2 811.7 868.3 911.7 921.9 928.2 19619.4 20721.7 20744.6 20745.5 20853.6 886.3 901.0 922.5 928.6 931.5 9712.8 10302.5 10901.1 10948.9 10954.1 499989.0 598874.0 618653.0 623341.0 633336.0 124406.0 126937.7 131748.0 135486.0 135598.0 873.2 965.1 987.6 990.9 1012.5 18203.3 18557.9 19038.4 19079.8 19099.7 49886.0 51949.9 52023.0 52530.5 52841.7 69967.7 70668.6 73455.0 74245.0 74541.6 6750.7 7377.6 7691.2 7845.3 8172.5 8997.5 12673.5 12686.7 12688.2 13014.8 10770.4 10791.7 10823.6 11154.3 11382.4 9286.3 10742.8 10865.6 10871.7 11042.5 9222.8 10767.3 10892.2 11098.6 11521.7 7318.7 7361.6 7438.6 7507.9 7519.1 9075.9 10008.6 10033.2 10317.3 10927.8 14:39:59 INFO - PROCESS | 1909 | 14:39:59 INFO - PROCESS | 1909 | [#3] mootools.html Cycles:180 Average:5219.94 Median:5058.46 stddev:2877.91 (56.9%) stddev-sans-first:2870.90 14:39:59 INFO - PROCESS | 1909 | Values: 1300.3 1443.3 1467.6 1611.0 1815.6 7297.2 7684.5 7898.8 8183.8 8184.3 8001.5 8120.3 8122.2 8126.6 8130.3 2260.9 2292.5 2293.2 2298.4 2298.8 2269.5 2312.1 2327.4 2328.3 2330.1 2240.7 2266.6 2299.1 2305.1 2306.1 1905.5 2226.7 2235.4 2265.7 2266.3 2272.2 2288.3 2293.5 2298.3 2300.6 7916.9 8002.4 8011.3 8033.2 8117.7 4850.5 5046.6 5070.3 5094.4 5096.7 2784.7 3184.4 3364.2 3429.2 3439.0 998.8 1004.2 1021.3 1031.6 1044.4 10162.3 10294.8 10297.5 10309.5 10345.8 4132.6 4203.4 4312.2 4313.0 4314.2 8377.7 8378.2 8393.7 8397.8 8408.7 4102.2 4898.7 4906.7 4925.5 4928.4 3911.0 4121.8 4268.5 4272.8 4606.0 3536.4 3562.6 3636.6 3640.9 3644.9 1075.6 1083.0 1092.6 1108.4 1109.8 679.3 872.7 883.3 905.2 909.7 5319.1 5389.3 5438.5 5477.5 5584.9 402.6 419.3 423.2 424.9 429.9 3720.1 3727.0 3731.9 3771.0 3818.4 9470.0 9746.7 9844.3 9851.4 9862.0 9785.9 9792.5 9801.1 9803.8 9809.0 7521.7 7629.2 7630.9 7642.8 7763.0 5183.4 5319.6 5423.2 5509.6 5561.6 9028.1 9059.3 9155.9 9161.1 9172.4 8313.1 8844.1 9055.7 9154.3 9183.7 5536.8 6486.8 6668.5 6907.7 6976.9 7655.6 7659.5 7679.5 7748.1 7795.3 6962.2 6982.1 6997.9 7010.1 7023.0 6816.8 6890.1 6894.0 6928.4 6946.8 6381.0 6516.6 6518.2 6526.4 6535.1 4556.0 4638.1 4642.9 4702.9 4763.4 4716.0 4762.2 4764.2 4884.0 4924.9 14:39:59 INFO - PROCESS | 1909 | 14:39:59 INFO - PROCESS | 1909 | [#4] prototype.html Cycles:180 Average:4166.73 Median:4526.91 stddev:1248.96 (27.6%) stddev-sans-first:1218.01 14:39:59 INFO - PROCESS | 1909 | Values: 285.1 295.6 302.4 309.8 312.1 4003.7 4991.1 5117.6 5143.2 5143.5 4167.5 4509.0 4700.4 5038.1 5043.8 3047.6 3871.4 3900.4 3924.0 3971.4 3443.8 3865.9 3912.6 3931.6 3976.1 3901.6 3902.6 3918.2 3924.3 3935.0 3756.9 3872.9 3913.2 3929.5 3941.3 3424.4 3428.5 3432.8 3434.2 3439.4 4900.1 4912.6 4925.0 4948.0 4974.3 4240.0 4345.8 4454.2 4477.3 4500.5 4349.8 4686.5 5257.6 5272.1 5303.0 3160.0 3745.6 3751.0 3751.5 3752.9 4482.0 5510.8 5540.1 5554.8 5604.0 3288.5 3605.7 3802.7 3821.9 3828.9 3428.6 3854.5 3863.5 3878.6 3905.6 3713.3 4418.7 4421.9 4427.4 4446.3 4287.6 4606.4 5261.8 5303.3 5363.6 1028.7 1104.8 1145.9 1177.7 1235.4 774.1 777.9 809.7 826.6 833.9 3915.7 4605.0 4891.3 5058.7 5143.9 4545.8 4983.0 5180.1 5450.1 5457.2 4209.9 4457.3 4779.8 5011.7 5012.7 2404.9 2569.3 2703.9 2704.9 2713.9 5102.5 5120.4 5236.6 5308.8 5320.7 4486.6 5031.3 5164.7 5274.6 5327.1 4434.4 5298.0 5328.3 5328.8 5348.6 4462.8 4544.8 4832.5 5052.7 5138.8 3961.7 4262.9 4765.3 4808.6 4906.6 4710.6 5042.6 5111.0 5305.3 5364.7 2950.6 3435.7 3444.4 3471.5 3475.6 4730.5 5121.9 5128.3 5146.5 5179.5 4198.8 4803.7 4966.6 5008.6 5009.5 3878.3 4054.8 4779.9 4984.2 5011.5 4372.0 4394.5 4885.2 4902.9 4924.4 4794.7 4986.1 5006.5 5086.3 5418.8 4006.7 4400.5 5280.4 5284.2 5300.2 14:39:59 INFO - PROCESS | 1909 | 14:39:59 INFO - PROCESS | 1909 | [#5] yui.html Cycles:180 Average:18519.63 Median:29236.13 stddev:26420.59 (90.4%) stddev-sans-first:26460.78 14:39:59 INFO - PROCESS | 1909 | Values: 688.6 693.1 699.2 719.5 751.1 14070.3 14655.9 14661.0 14687.7 14726.0 12997.2 13017.9 13045.2 13126.1 13137.0 647.2 669.7 675.3 795.7 824.6 808.9 816.8 823.4 823.5 824.0 870.8 874.7 875.5 875.6 876.8 677.6 695.6 819.3 865.5 869.0 637.9 641.2 641.2 643.5 647.1 14227.7 14366.9 14368.9 14380.0 14405.4 13599.0 13814.4 13829.1 13832.0 13896.8 10963.7 11390.0 11413.8 11560.3 11904.3 3578.1 3669.7 3690.0 3711.1 3788.9 72036.0 75134.6 75157.0 86810.0 92698.5 10430.4 10707.6 10813.2 10851.1 10935.4 20849.8 21050.0 21110.3 21181.8 21370.1 7753.9 7766.1 7770.1 7836.3 7903.8 4163.8 4222.9 4290.0 4360.0 4426.2 3295.4 3374.9 3418.1 3421.3 3426.4 613.3 616.2 624.9 628.7 631.6 570.5 639.1 641.3 650.2 686.0 8688.4 8812.3 8812.7 8877.0 9098.2 651.3 661.9 663.5 668.0 669.1 4083.9 4355.5 4384.5 4386.4 4495.2 69719.0 96739.5 109046.0 110950.0 114679.0 62806.0 62817.1 63539.0 71254.6 75821.6 77821.6 83461.6 84409.7 85236.1 85829.0 8390.0 8395.8 8477.4 8492.5 8758.3 47303.0 47823.8 48307.0 48677.0 50245.0 47965.0 57859.0 67576.0 69789.0 70834.0 11518.4 11696.2 11705.8 11815.1 12036.8 11575.2 11724.2 11738.6 11896.4 12634.8 11524.5 11790.6 11955.5 12047.5 12115.9 10886.3 11993.1 12090.4 12224.3 12233.8 10768.4 11189.3 11429.3 11433.5 11500.7 9051.8 9344.3 9391.5 9516.8 10000.0 9199.5 9509.6 9817.0 9835.1 9859.4 14:39:59 INFO - PROCESS | 1909 | -------- Summary: end -------- 14:39:59 INFO - PROCESS | 1909 | 14:40:00 INFO - TEST-INFO | 1909: exit 0 14:40:00 INFO - TEST-OK | dromaeo_css | took 1118078ms 14:40:00 INFO - TEST-START | kraken 14:40:00 INFO - operating with platform_type : mac_ 14:40:00 INFO - Initialising browser for kraken test... 14:40:00 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmptWkK6N/profile http://localhost:53537/getInfo.html) 14:40:02 INFO - PROCESS | 1913 | __metrics Screen width/height:1600/1200 14:40:02 INFO - PROCESS | 1913 | colorDepth:24 14:40:02 INFO - PROCESS | 1913 | Browser inner width/height: 1024/689 14:40:02 INFO - PROCESS | 1913 | __metrics 14:40:02 INFO - PROCESS | 1913 | 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" 14:40:02 INFO - PROCESS | 1913 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:40:03 INFO - TEST-INFO | 1913: exit 0 14:40:03 INFO - Browser initialized. 14:40:03 INFO - Running cycle 1/1 for kraken test... 14:40:03 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmptWkK6N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:40:16 INFO - PROCESS | 1914 | RSS: Main: 222339072 14:40:16 INFO - PROCESS | 1914 | 14:40:18 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53537/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53537/tests/kraken/driver.html?testName=audio-beat-detection) 14:40:19 INFO - PROCESS | 1914 | RSS: Main: 270286848 14:40:19 INFO - PROCESS | 1914 | 14:40:22 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53537/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53537/tests/kraken/driver.html?testName=audio-dft) 14:40:22 INFO - PROCESS | 1914 | RSS: Main: 284712960 14:40:22 INFO - PROCESS | 1914 | 14:40:26 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53537/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53537/tests/kraken/driver.html?testName=audio-fft) 14:40:26 INFO - PROCESS | 1914 | RSS: Main: 380477440 14:40:26 INFO - PROCESS | 1914 | 14:40:28 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53537/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53537/tests/kraken/driver.html?testName=audio-oscillator) 14:40:29 INFO - PROCESS | 1914 | RSS: Main: 284647424 14:40:29 INFO - PROCESS | 1914 | 14:40:31 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53537/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53537/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:40:31 INFO - PROCESS | 1914 | RSS: Main: 286724096 14:40:31 INFO - PROCESS | 1914 | 14:40:34 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53537/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53537/tests/kraken/driver.html?testName=imaging-darkroom) 14:40:35 INFO - PROCESS | 1914 | RSS: Main: 327581696 14:40:35 INFO - PROCESS | 1914 | 14:40:38 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53537/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53537/tests/kraken/driver.html?testName=imaging-desaturate) 14:40:39 INFO - PROCESS | 1914 | RSS: Main: 327221248 14:40:39 INFO - PROCESS | 1914 | 14:40:41 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53537/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53537/tests/kraken/driver.html?testName=json-parse-financial) 14:40:42 INFO - PROCESS | 1914 | RSS: Main: 324288512 14:40:42 INFO - PROCESS | 1914 | 14:40:44 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53537/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53537/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:40:44 INFO - PROCESS | 1914 | RSS: Main: 300150784 14:40:44 INFO - PROCESS | 1914 | 14:40:46 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53537/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53537/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:40:47 INFO - PROCESS | 1914 | RSS: Main: 301195264 14:40:47 INFO - PROCESS | 1914 | 14:40:49 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53537/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53537/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:40:50 INFO - PROCESS | 1914 | RSS: Main: 304406528 14:40:50 INFO - PROCESS | 1914 | 14:40:53 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53537/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53537/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:40:53 INFO - PROCESS | 1914 | RSS: Main: 317931520 14:40:53 INFO - PROCESS | 1914 | 14:40:56 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53537/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53537/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:40:57 INFO - PROCESS | 1914 | RSS: Main: 311652352 14:40:57 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53537/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53537/tests/kraken/driver.html?testName=ai-astar) 14:40:59 INFO - PROCESS | 1914 | RSS: Main: 356106240 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | __start_tp_report 14:40:59 INFO - PROCESS | 1914 | _x_x_mozilla_page_load 14:40:59 INFO - PROCESS | 1914 | _x_x_mozilla_page_load_details 14:40:59 INFO - PROCESS | 1914 | |i|pagename|runs| 14:40:59 INFO - PROCESS | 1914 | |0;ai-astar;106;113;113;115;114;114;114;116;114;114 14:40:59 INFO - PROCESS | 1914 | |1;audio-beat-detection;146;112;112;146;109;113;113;109;139;110 14:40:59 INFO - PROCESS | 1914 | |2;audio-dft;184;186;183;185;183;183;184;183;184;185 14:40:59 INFO - PROCESS | 1914 | |3;audio-fft;81;59;59;92;79;83;83;62;83;58 14:40:59 INFO - PROCESS | 1914 | |4;audio-oscillator;87;70;94;69;68;70;90;67;68;92 14:40:59 INFO - PROCESS | 1914 | |5;imaging-gaussian-blur;88;85;88;85;88;85;89;86;93;130 14:40:59 INFO - PROCESS | 1914 | |6;imaging-darkroom;98;113;165;126;98;98;98;97;97;96 14:40:59 INFO - PROCESS | 1914 | |7;imaging-desaturate;81;81;94;126;81;81;82;83;81;81 14:40:59 INFO - PROCESS | 1914 | |8;json-parse-financial;54;55;54;53;58;62;61;60;58;57 14:40:59 INFO - PROCESS | 1914 | |9;json-stringify-tinderbox;67;73;70;74;72;70;71;71;70;70 14:40:59 INFO - PROCESS | 1914 | |10;stanford-crypto-aes;84;73;87;94;102;84;84;84;96;104 14:40:59 INFO - PROCESS | 1914 | |11;stanford-crypto-ccm;142;147;152;142;141;144;145;142;189;139 14:40:59 INFO - PROCESS | 1914 | |12;stanford-crypto-pbkdf2;209;223;165;165;164;164;163;164;164;164 14:40:59 INFO - PROCESS | 1914 | |13;stanford-crypto-sha256-iterative;67;68;67;66;71;65;87;67;67;66 14:40:59 INFO - PROCESS | 1914 | __end_tp_report 14:40:59 INFO - PROCESS | 1914 | __start_cc_report 14:40:59 INFO - PROCESS | 1914 | _x_x_mozilla_cycle_collect,1971 14:40:59 INFO - PROCESS | 1914 | __end_cc_report 14:40:59 INFO - PROCESS | 1914 | __startTimestamp1484260859371__endTimestamp 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | ------- Summary: start ------- 14:40:59 INFO - PROCESS | 1914 | Number of tests: 14 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | [#0] ai-astar Cycles:10 Average:113.30 Median:114.00 stddev:2.71 (2.4%) stddev-sans-first:0.93 14:40:59 INFO - PROCESS | 1914 | Values: 106.0 113.0 113.0 115.0 114.0 114.0 114.0 116.0 114.0 114.0 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | [#1] audio-beat-detection Cycles:10 Average:120.90 Median:113.00 stddev:15.89 (14.1%) stddev-sans-first:14.02 14:40:59 INFO - PROCESS | 1914 | Values: 146.0 112.0 112.0 146.0 109.0 113.0 113.0 109.0 139.0 110.0 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.00 stddev:1.05 (0.6%) stddev-sans-first:1.12 14:40:59 INFO - PROCESS | 1914 | Values: 184.0 186.0 183.0 185.0 183.0 183.0 184.0 183.0 184.0 185.0 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | [#3] audio-fft Cycles:10 Average:73.90 Median:82.00 stddev:12.87 (15.7%) stddev-sans-first:13.39 14:40:59 INFO - PROCESS | 1914 | Values: 81.0 59.0 59.0 92.0 79.0 83.0 83.0 62.0 83.0 58.0 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:78.50 stddev:11.57 (14.7%) stddev-sans-first:11.75 14:40:59 INFO - PROCESS | 1914 | Values: 87.0 70.0 94.0 69.0 68.0 70.0 90.0 67.0 68.0 92.0 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:88.00 stddev:13.68 (15.5%) stddev-sans-first:14.44 14:40:59 INFO - PROCESS | 1914 | Values: 88.0 85.0 88.0 85.0 88.0 85.0 89.0 86.0 93.0 130.0 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | [#6] imaging-darkroom Cycles:10 Average:108.60 Median:97.50 stddev:22.06 (22.6%) stddev-sans-first:23.06 14:40:59 INFO - PROCESS | 1914 | Values: 98.0 113.0 165.0 126.0 98.0 98.0 98.0 97.0 97.0 96.0 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | [#7] imaging-desaturate Cycles:10 Average:87.10 Median:81.00 stddev:14.25 (17.6%) stddev-sans-first:14.94 14:40:59 INFO - PROCESS | 1914 | Values: 81.0 81.0 94.0 126.0 81.0 81.0 82.0 83.0 81.0 81.0 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:58.00 stddev:3.16 (5.4%) stddev-sans-first:3.13 14:40:59 INFO - PROCESS | 1914 | Values: 54.0 55.0 54.0 53.0 58.0 62.0 61.0 60.0 58.0 57.0 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.00 stddev:1.93 (2.7%) stddev-sans-first:1.48 14:40:59 INFO - PROCESS | 1914 | Values: 67.0 73.0 70.0 74.0 72.0 70.0 71.0 71.0 70.0 70.0 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:84.00 stddev:9.59 (11.4%) stddev-sans-first:9.98 14:40:59 INFO - PROCESS | 1914 | Values: 84.0 73.0 87.0 94.0 102.0 84.0 84.0 84.0 96.0 104.0 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:144.50 stddev:14.76 (10.2%) stddev-sans-first:15.48 14:40:59 INFO - PROCESS | 1914 | Values: 142.0 147.0 152.0 142.0 141.0 144.0 145.0 142.0 189.0 139.0 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.50 Median:164.50 stddev:22.13 (13.5%) stddev-sans-first:19.63 14:40:59 INFO - PROCESS | 1914 | Values: 209.0 223.0 165.0 165.0 164.0 164.0 163.0 164.0 164.0 164.0 14:40:59 INFO - PROCESS | 1914 | 14:40:59 INFO - PROCESS | 1914 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:67.00 stddev:6.49 (9.7%) stddev-sans-first:6.84 14:40:59 INFO - PROCESS | 1914 | Values: 67.0 68.0 67.0 66.0 71.0 65.0 87.0 67.0 67.0 66.0 14:40:59 INFO - PROCESS | 1914 | -------- Summary: end -------- 14:40:59 INFO - PROCESS | 1914 | 14:41:00 INFO - TEST-INFO | 1914: exit 0 14:41:00 INFO - TEST-OK | kraken | took 60410ms 14:41:00 INFO - SUITE-END | took 1178s 14:41:00 INFO - Completed test suite (00:19:38) 14:41:00 INFO - javascript benchmark 14:41:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2748.4365705030464, 3255.3977514159883, 3266.5753452051213, 3278.9508157377645, 3291.814946619218, 5453.700046497441, 5484.2596249506305, 5498.0653288940875, 5503.779848806048, 5513.834584962453, 5455.699936503491, 5486.231927530149, 5498.642588231762, 5504.064309067458, 5523.861903452405, 4983.576396006344, 5011.473795251501, 5074.873128171788, 5116.437191908887, 5117.104506711329, 5005.399352077756, 5009.799608015694, 5017.172166592508, 5042.672226305297, 5119.948800512003, 5044.646874718771, 5074.112030394685, 5080.466551012146, 5089.3638295213095, 5098.0, 5074.949250507485, 5093.694378337274, 5105.463926287724, 5119.948800511985, 5162.199859021857, 4833.178359678863, 5055.620828437882, 5070.645054846163, 5073.0, 5087.669301495391, 5348.839534813923, 5403.756830942578, 5404.405515393303, 5460.099083651193, 5460.31746031746, 5900.262467191601, 5904.055351143839, 5945.16767652529, 5975.1037344398255, 5977.013792724195, 6089.908651370233, 6090.299615544225, 6097.146399504073, 6099.603525770811, 6129.816105516842, 5411.431799661058, 5469.72651367434, 5504.2018907258225, 5554.944450555524, 5579.414161513064, 6166.167567378437, 6198.876022479615, 6205.689715514297, 6206.10011548332, 6208.379162083846, 5829.0, 5834.591578589543, 5850.063989761703, 5860.0, 5874.441928016831, 5855.473007429352, 5886.735096920649, 5898.616589921641, 5906.970465147732, 5943.375945525658, 5836.066229403275, 5854.034084376115, 5884.205632239678, 5888.735006924613, 5936.614120082181, 5914.4381283777975, 5935.287765468085, 5944.881102377928, 5947.0, 5956.076808094752, 4684.016356565159, 4692.6245900327895, 4749.453812811502, 4756.1438940990265, 4786.353842231324, 4243.554426785205, 4252.21334053195, 4294.9785251073545, 4332.220200363901, 4342.82628694855, 5462.863428414242, 5525.834224973257, 5532.059549876451, 5557.610967232255, 5561.555075593943, 6006.399360064046, 6017.488513476316, 6022.638641681513, 6027.065804800263, 6031.577789554689, 5435.972820135953, 5451.672899626035, 5492.835214943558, 5534.7786088556, 5535.806246781343, 4354.651627869763, 5156.767945442464, 5190.429052804188, 5219.686818790885, 5243.659162154448, 6218.9378106218355, 6241.251049874044, 6248.125262463259, 6252.468540173954, 6263.530235232284, 5851.941480585139, 5879.794207202727, 5898.616589921641, 6039.486643635164, 6050.45545900871, 6053.182820319201, 6128.50971922254, 6135.538906164888, 6157.0456579230295, 6166.229221347332, 5881.117832325185, 5921.1414344918885, 5929.407059294036, 5929.792457263975, 5965.463108320272, 6029.3669164737075, 6043.879122417615, 6057.273127224761, 6060.0, 6069.2110025696375, 5652.88694226105, 5704.0588302929555, 5730.054541000688, 5751.8562035949435, 5815.563832712648, 5710.771569137354, 5768.769249229984, 5786.247787787729, 5799.942000580109, 5819.272590926134, 5540.889182216414, 5621.943780562142, 5656.915146272891, 5673.262475878109, 5684.602077854676, 5628.662280263197, 5646.9152962704775, 5790.739416726173, 5791.189233507396, 5817.95276850171, 5628.831135065954, 5631.183478395692, 5668.979583674978, 5733.111367738007, 5735.197072409783], "value": 5478.44692881071, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.84365315792644, 475.4508542633259, 501.92972983782255, 514.2491961735866, 515.9948400515993, 29283.0, 30851.0, 37067.25865482689, 38191.23617527655, 38336.0, 39391.40912886309, 39551.20897582047, 39761.602383976155, 39870.003249918766, 39948.80025599868, 8977.91022089779, 9164.221041211506, 9166.633334666605, 9169.770755731093, 9172.357934944557, 8359.247667709906, 8560.0583935767, 8569.400141990063, 8659.220670139686, 9244.352895297332, 8531.018932822742, 11049.116070714364, 11292.096632269398, 11297.830532542019, 11485.770284594302, 11153.442327883615, 11201.66395008151, 11208.103351731923, 11214.439278036107, 11230.55077796887, 11074.169437292156, 11146.554137834477, 11164.274322169114, 11165.83251251232, 11171.720706982309, 37921.431178532344, 38159.80920095409, 38162.61837381646, 38177.0, 38226.61773382286, 3213.8714451422147, 3234.3531293741344, 3274.3778682050574, 3398.2863598644312, 3514.9982255057184, 27923.58114628282, 27969.881204751786, 28240.576391354145, 28631.0, 28815.0, 3162.36752649471, 3207.0975766930965, 3217.3726123405713, 3232.272738633812, 3278.688524590184, 412863.0, 415429.0, 416028.0, 416565.999999997, 424842.0, 32400.67599324024, 32948.341033179204, 33252.50121248183, 34126.65873341235, 58117.70941145267, 34324.0, 34330.65669343275, 34345.65654343425, 34387.4841877372, 34414.13964650904, 14118.435262589586, 14258.429662813578, 14270.785938210935, 14271.714565708628, 14280.42878284857, 12079.456424460921, 12186.756264874653, 12199.39003049844, 12203.816942745865, 12282.201656892274, 6598.47212223021, 7226.205117437078, 7401.259874012556, 7733.0, 7808.960955195302, 3201.7598680099086, 3280.950785738216, 3287.1453422109944, 3306.0, 3314.767966242388, 875.868619707049, 944.4710961861242, 969.4425705219386, 1048.3814549415729, 1082.6643740440488, 24009.720534629334, 28057.43885122286, 28802.84788608432, 28979.710202897702, 29180.85409572981, 1021.7701017271203, 1042.7914417116608, 1059.1209296284067, 1079.1474734959452, 1142.5258517715056, 11610.6516804496, 15058.397664093533, 15084.547463576111, 15153.469628562947, 15408.61478463025, 541621.0, 624910.8754456198, 630811.0, 632502.8374858188, 632714.0, 47538.0, 48323.27515087277, 54122.0, 54296.18555721588, 55587.72206138943, 1333.8999575031912, 1352.1684164238923, 1374.5326588959801, 1377.9104358216684, 1378.2144177818548, 28795.704193310932, 29382.41235175327, 29720.256993575334, 30330.241743955696, 30417.84791076119, 47503.52496474991, 47992.04015919732, 48035.27947080727, 48122.5187748132, 48176.27735584039, 652634.0, 672192.0, 675800.0, 822576.0, 823874.0, 37973.81013094917, 38388.0, 38553.843384698506, 39193.0, 39937.600623993385, 42993.3550996729, 43289.91775205645, 43734.781326093165, 43880.56119438765, 43984.34023489714, 31970.52044219385, 35879.46180807238, 36143.73496927595, 36518.26963460769, 36518.90443286706, 35508.934731958085, 36789.44815827818, 36831.63168368282, 36876.0, 37408.0, 36542.0, 37273.88178354654, 37448.62551374451, 37490.437643435915, 37506.87479375623, 31495.055148345586, 33359.66640333566, 33406.164845879044, 33604.99185024453, 33664.3267134651, 24790.884410201947, 25124.497510050063, 25364.475155775624, 25386.492270154125, 25525.489490209722], "value": 18243.50624326121, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.3411404939713, 355.58219092566236, 368.2800125754149, 375.26635427738773, 396.6588733689027, 14310.642233944169, 14591.416343346267, 15299.694006119886, 15534.22328883557, 18905.7164142538, 16243.51269461918, 19516.926569038696, 19818.20727170915, 19882.204711811548, 19970.600587988232, 5655.26481557397, 6033.426824451692, 6043.939560604382, 6048.0625503047095, 6048.334683184846, 5738.110592858093, 5860.560457965648, 5911.88176236475, 5914.645121292715, 5977.88044239115, 5894.263217097863, 5898.233229680157, 5905.379935106816, 5909.054551271797, 5909.911351329733, 5639.182318563806, 5826.1552074948895, 5874.441928016831, 5877.970610146944, 5896.528277737771, 5153.9742301288625, 5157.149070403379, 5159.1229490986625, 5161.845144645667, 5164.845054648366, 24076.39809004789, 24514.877425612936, 24719.134830281033, 24737.505249895083, 25009.249722508353, 2598.5322641924636, 2662.3211081174177, 2724.182745176459, 3082.1370016395445, 3127.0149902780777, 13644.590662280048, 15037.924810375987, 15470.0, 15527.611809704735, 15538.689226215412, 1780.6883795335893, 1788.6333301673126, 1809.6471188118192, 1834.4038187589088, 1860.4139695995718, 107037.46481267543, 107231.46384268107, 107826.0, 108036.4598177012, 108769.0, 8282.834343313221, 8723.255348930239, 8898.4660920345, 9379.343445958717, 9497.477638729799, 12269.386530673431, 12361.505539778487, 12367.75264494723, 12404.75190496185, 12408.0, 7760.805979850436, 7985.68057277714, 7994.960025199837, 8065.556394398247, 8069.515829050276, 7973.401994850409, 8034.317083047889, 8297.211764882326, 8468.745937621881, 8494.490330580185, 3865.0, 3873.457715919717, 3879.3017256893468, 3938.369860822255, 3945.9605403945593, 1888.2824526679774, 1896.8387687046757, 1900.5913728548428, 1900.6008738164865, 1908.2367053178561, 811.6591031766673, 868.3227082875368, 911.7128104647015, 921.9262459003397, 928.166870430907, 19619.411417657495, 20721.68917466239, 20744.58510829805, 20745.481362966046, 20853.582928341653, 886.2865393358409, 900.9594568244445, 922.4834092907993, 928.6471140966524, 931.4830269200523, 9712.805743885225, 10302.484875756183, 10901.1279097672, 10948.890511094947, 10954.123670106372, 499989.0, 598874.0, 618653.0, 623341.0, 633336.0, 124406.0, 126937.73062269259, 131748.0, 135486.0, 135598.0, 873.2359185712502, 965.0879918476971, 987.5605355616682, 990.9157721593601, 1012.4684540616088, 18203.271869125616, 18557.886526807895, 19038.428847134608, 19079.809201907803, 19099.713504297724, 49886.0, 51949.8830070197, 52023.0, 52530.47469525409, 52841.735791320796, 69967.65016174887, 70668.64665676639, 73455.0, 74245.0, 74541.62729186319, 6750.729970801113, 7377.557346559223, 7691.230876912264, 7845.29392354668, 8172.509649421054, 8997.460152390877, 12673.493060277486, 12686.746265074831, 12688.17526860751, 13014.804777928528, 10770.353778773298, 10791.73020674458, 10823.56705731762, 11154.274972126786, 11382.430878455878, 9286.34995550305, 10742.785144296913, 10865.565377384815, 10871.67384978452, 11042.503087360927, 9222.769430764285, 10767.300125491818, 10892.237543372205, 11098.556057757922, 11521.711957201138, 7318.6706598202145, 7361.558306501627, 7438.62806859644, 7507.88738168917, 7519.097708275042, 9075.863862041942, 10008.64969726056, 10033.19734421259, 10317.329373590692, 10927.836082458929], "value": 9243.331792253808, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.304337179609, 1443.3216388297499, 1467.5597320803759, 1610.983890161098, 1815.5824160443099, 7297.197308296083, 7684.538927664337, 7898.763037108896, 8183.795405114875, 8184.345252379795, 8001.519908805476, 8120.309773669245, 8122.1877812218745, 8126.593670316475, 8130.308923741489, 2260.9321720348416, 2292.4612716011725, 2293.243229734192, 2298.402415371999, 2298.7586303438147, 2269.523400085984, 2312.0867257433306, 2327.359976006603, 2328.3480625424822, 2330.0796185506715, 2240.675102110193, 2266.580682573729, 2299.1378233162563, 2305.101010605865, 2306.08909480755, 1905.504568812112, 2226.6640015990356, 2235.441139715071, 2265.67147763575, 2266.331432227497, 2272.1593010586025, 2288.290629904735, 2293.472501324688, 2298.321995011476, 2300.562893050315, 7916.881246781303, 8002.439829211958, 8011.319037881787, 8033.236842499953, 8117.715879944174, 4850.53919877611, 5046.621503387224, 5070.29015937769, 5094.439611642716, 5096.719680417576, 2784.665840099181, 3184.4268031754277, 3364.243045314809, 3429.176997520602, 3438.9828050859837, 998.7952469743685, 1004.206676725386, 1021.3463383434607, 1031.6079889641962, 1044.4307852220559, 10162.339447935861, 10294.794104117876, 10297.485125743682, 10309.484525773682, 10345.844812327821, 4132.607402296777, 4203.390508376268, 4312.202242585132, 4313.029568347097, 4314.22343978087, 8377.664893404195, 8378.162183781573, 8393.748187554384, 8397.832043359098, 8408.747737567883, 4102.2410853992105, 4898.730569818623, 4906.681065730716, 4925.482824303468, 4928.433230178503, 3910.980445097813, 4121.814518346681, 4268.466441694788, 4272.764997925082, 4606.032733126014, 3536.3634545781492, 3562.590302115238, 3636.636336366342, 3640.9453858192146, 3644.927101457956, 1075.5858994287255, 1082.9945850270856, 1092.579356947581, 1108.4402376799826, 1109.8113320735495, 679.3138929681057, 872.6814712629971, 883.3286702106445, 905.2215095018279, 909.6907051602487, 5319.122344813062, 5389.299391079135, 5438.510534051875, 5477.507024367826, 5584.944150558524, 402.6295807856779, 419.3374468340015, 423.18325631530826, 424.90227247733196, 429.8973133911507, 3720.069982504374, 3727.0496023514374, 3731.9253614928093, 3770.962290377061, 3818.4463252828186, 9469.952650236704, 9746.707598772047, 9844.261680374, 9851.359661621971, 9861.9644937282, 9785.853212201822, 9792.510374481248, 9801.068898454636, 9803.754906127262, 9809.019098090133, 7521.7367392142105, 7629.198934111839, 7630.923690763132, 7642.808929776689, 7763.029621297338, 5183.429822719422, 5319.601029922694, 5423.2407462954425, 5509.559235261089, 5561.63849349791, 9028.058967322057, 9059.320550958573, 9155.862662060208, 9161.129692679186, 9172.403793753385, 8313.08556058821, 8844.115588441104, 9055.68305109318, 9154.26765858743, 9183.724488265363, 5536.833894983157, 6486.772962946463, 6668.533202675766, 6907.723691052302, 6976.860462790614, 7655.617219139132, 7659.502132361378, 7679.539227646359, 7748.070231572022, 7795.298423141718, 6962.164540255202, 6982.1272340957385, 6997.860042799014, 7010.088688470466, 7022.964885175746, 6816.7614133505085, 6890.069840571659, 6893.965530172317, 6928.445724342141, 6946.826329341605, 6380.968095159495, 6516.609003459807, 6518.217813862367, 6526.379993900572, 6535.085088087577, 4556.0, 4638.095571363552, 4642.907141857211, 4702.858914232578, 4763.404574428197, 4715.976420117878, 4762.214234651382, 4764.213904705684, 4883.974365383309, 4924.8768780780765], "value": 4156.707303266888, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.1417234125283, 295.5669943532725, 302.4435039527269, 309.7676742443166, 312.1089290076832, 4003.719739618224, 4991.126552853254, 5117.59059275258, 5143.228515722643, 5143.485651434859, 4167.520735115462, 4508.97745511272, 4700.438261147674, 5038.143515602347, 5043.823466178685, 3047.603811504501, 3871.3999330103807, 3900.4344370066324, 3924.0, 3971.4185779243435, 3443.7933723976516, 3865.93686736147, 3912.628300311466, 3931.5871833457504, 3976.13718122951, 3901.648851603355, 3902.63407807268, 3918.196769662227, 3924.2543916655886, 3934.960650393488, 3756.8872933811895, 3872.9031774205587, 3913.236918800835, 3929.469521614574, 3941.31027070263, 3424.400729872262, 3428.468587368962, 3432.828358582061, 3434.227298857762, 3439.4152993991083, 4900.142475066885, 4912.606991440676, 4925.014997000614, 4947.975260123676, 4974.278729584226, 4240.0, 4345.760983145926, 4454.175977444151, 4477.306017567283, 4500.504944456107, 4349.826006959718, 4686.507916668735, 5257.631965762398, 5272.103742363769, 5303.011370735974, 3159.961824623128, 3745.5879853216125, 3751.0307088778336, 3751.456038874348, 3752.887413377603, 4482.0, 5510.779568817243, 5540.085885828834, 5554.77780888768, 5604.010576245415, 3288.523164141186, 3605.675489205984, 3802.6767724743154, 3821.923561528754, 3828.9413221984787, 3428.5714285714284, 3854.5374555053572, 3863.517060367454, 3878.5539662939123, 3905.6484916357663, 3713.2573485303046, 4418.690691651618, 4421.911561768747, 4427.358033085185, 4446.3330500424545, 4287.592678695519, 4606.355110284563, 5261.842144735664, 5303.310569626001, 5363.597730170253, 1028.711960651019, 1104.8012905997084, 1145.8510393648771, 1177.717347836513, 1235.4063258396568, 774.0633833062058, 777.8988731464987, 809.6599428240153, 826.5867066466767, 833.8957630296213, 3915.686745060388, 4604.976975115103, 4891.31521586971, 5058.721770302669, 5143.897122057613, 4545.795139495953, 4983.0, 5180.119379705459, 5450.073487507133, 5457.189547383101, 4209.894752631209, 4457.286834106528, 4779.828941909254, 5011.674241174312, 5012.674176178537, 2404.927852164438, 2569.331973686855, 2703.864806759654, 2704.905328313539, 2713.8778754956074, 5102.464241254689, 5120.359955005624, 5236.612297741101, 5308.840734777963, 5320.654157479828, 4486.551344865553, 5031.320771695774, 5164.746209887747, 5274.604404669665, 5327.111208817508, 4434.445694288214, 5298.0, 5328.280682107943, 5328.8401347959625, 5348.5988550858065, 4462.754548499864, 4544.840930567414, 4832.516748325139, 5052.747362631927, 5138.845834624967, 3961.7028722845325, 4262.936055959101, 4765.26138448541, 4808.639352048541, 4906.589920005562, 4710.599599033983, 5042.596592272536, 5110.962339751703, 5305.283786688902, 5364.651297665795, 2950.5869178315484, 3435.656434356544, 3444.432783083641, 3471.5486986691567, 3475.58293004841, 4730.526947305242, 5121.8719532012, 5128.256382835165, 5146.536811686966, 5179.4561571036, 4198.8243291878325, 4803.711777293373, 4966.602671786321, 5008.620905070063, 5009.524095210949, 3878.3406820841087, 4054.79726013704, 4779.904401911873, 4984.211998880949, 5011.546651471178, 4371.978140109279, 4394.450693663292, 4885.193942999365, 4902.8774280643265, 4924.359833221658, 4794.736289504111, 4986.1329735403015, 5006.474320196473, 5086.262491938574, 5418.832467013226, 4006.6594339480303, 4400.515943246304, 5280.419153893145, 5284.180951952453, 5300.236831849276], "value": 3770.575838215463, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.5799662206055, 693.0782059860386, 699.1889408286389, 719.5071376107367, 751.0911796725961, 14070.296485175752, 14655.926720366384, 14661.047031942917, 14687.70624587509, 14725.969182157254, 12997.220166789975, 13017.934910325435, 13045.152065115784, 13126.146800457987, 13137.0, 647.2427260105676, 669.6517810738413, 675.2774531251563, 795.7453614843223, 824.6165533027141, 808.8624933761246, 816.8325493273865, 823.3742355809568, 823.4853216739538, 824.0399934076823, 870.7953630896734, 874.715717391847, 875.4747151708956, 875.5622188905547, 876.7720392697912, 677.552815142004, 695.58265040975, 819.3404309530825, 865.4720620421538, 869.0049892872657, 637.8660481298933, 641.1888960465016, 641.1888960465016, 643.4562794438692, 647.1296106736479, 14227.715445691028, 14366.856331436657, 14368.856311436857, 14380.0, 14405.423783048771, 13598.980076494303, 13814.447422103103, 13829.10110842792, 13832.0, 13896.791548126786, 10963.671089867317, 11390.03184729299, 11413.79481824054, 11560.30638161713, 11904.285742855456, 3578.1233597768455, 3669.7064234861145, 3690.0, 3711.109333759905, 3788.867389641377, 72036.0, 75134.62432687856, 75157.0, 86810.0, 92698.53650731771, 10430.426326552113, 10707.625233116803, 10813.243072984973, 10851.077658399125, 10935.398553079656, 20849.791502084787, 21050.0, 21110.261140859995, 21181.78818211798, 21370.145194192057, 7753.883691744629, 7766.106897706723, 7770.067591889009, 7836.294733474015, 7903.752846816562, 4163.770992595376, 4222.873313800591, 4290.0, 4360.018995725936, 4426.181156485996, 3295.3574053059424, 3374.865005399806, 3418.1454636340914, 3421.3328400962073, 3426.417509023472, 613.2671457608116, 616.2358675242667, 624.8594066335129, 628.6887990444668, 631.62733986947, 570.5036618142243, 639.0765344077856, 641.2913730327921, 650.2392201124601, 685.9657017149123, 8688.391812573185, 8812.295016398673, 8812.735617931472, 8877.023527411979, 9098.226650734628, 651.3006125208859, 661.8510835062073, 663.4559661077966, 667.9632620205936, 669.1334721535623, 4083.9183216335505, 4355.542668019875, 4384.544654993214, 4386.43286982792, 4495.190865644216, 69719.0, 96739.51630241804, 109046.0, 110950.0, 114679.0, 62806.0, 62817.057744133876, 63539.0, 71254.64372678104, 75821.62089189519, 77821.61089194518, 83461.58269208494, 84409.73385399223, 85236.14763852282, 85829.0, 8389.993200815697, 8395.832083358177, 8477.364197685074, 8492.490450572739, 8758.343124265579, 47303.0, 47823.76088119537, 48307.03385932332, 48677.02645947132, 50245.0, 47965.0, 57859.0, 67576.0, 69789.0, 70834.0, 11518.424078796193, 11696.239744416585, 11705.824412633647, 11815.113866459878, 12036.819447708467, 11575.189736718687, 11724.237924535219, 11738.647840564796, 11896.405179741152, 12634.810477842655, 11524.539018439504, 11790.587329443428, 11955.521779129085, 12047.457864396298, 12115.939420302842, 10886.29239099456, 11993.1005174614, 12090.395480225778, 12224.266544007389, 12233.816492752794, 10768.3538987661, 11189.272697274651, 11429.314241145557, 11433.485493152995, 11500.722294146128, 9051.818963620823, 9344.252459803063, 9391.530423478935, 9516.762080948032, 9999.950000249952, 9199.494027828534, 9509.572069257038, 9816.950915245377, 9835.114839664464, 9859.35914165577], "value": 6929.737215096746, "unit": "score"}], "name": "dromaeo_css", "value": 6816.891604954359, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 113.0, 113.0, 115.0, 114.0, 114.0, 114.0, 116.0, 114.0, 114.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 112.0, 112.0, 146.0, 109.0, 113.0, 113.0, 109.0, 139.0, 110.0], "value": 120.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 183.0, 185.0, 183.0, 183.0, 184.0, 183.0, 184.0, 185.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 92.0, 79.0, 83.0, 83.0, 62.0, 83.0, 58.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 94.0, 69.0, 68.0, 70.0, 90.0, 67.0, 68.0, 92.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 88.0, 85.0, 89.0, 86.0, 93.0, 130.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 113.0, 165.0, 126.0, 98.0, 98.0, 98.0, 97.0, 97.0, 96.0], "value": 108.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 94.0, 126.0, 81.0, 81.0, 82.0, 83.0, 81.0, 81.0], "value": 87.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 53.0, 58.0, 62.0, 61.0, 60.0, 58.0, 57.0], "value": 57.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 70.0, 74.0, 72.0, 70.0, 71.0, 71.0, 70.0, 70.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 73.0, 87.0, 94.0, 102.0, 84.0, 84.0, 84.0, 96.0, 104.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 147.0, 152.0, 142.0, 141.0, 144.0, 145.0, 142.0, 189.0, 139.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 223.0, 165.0, 165.0, 164.0, 164.0, 163.0, 164.0, 164.0, 164.0], "value": 174.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 66.0, 71.0, 65.0, 87.0, 67.0, 67.0, 66.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1466.1, "alertThreshold": 2.0}]} 14:41:00 INFO - Return code: 0 14:41:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:41:00 INFO - # TBPL SUCCESS # 14:41:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:41:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:41:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:41:00 INFO - 2017-01-12 14:41:00.713 screenresolution[1918:17919] starting screenresolution argv=screenresolution get 14:41:00 INFO - 2017-01-12 14:41:00.716 screenresolution[1918:17919] Display 0: 1600x1200x32@60 14:41:00 INFO - 2017-01-12 14:41:00.723 screenresolution[1919:17922] starting screenresolution argv=screenresolution list 14:41:00 INFO - Available Modes on Display 0 14:41:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:41:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:41:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:41:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:41:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:41:00 INFO - Intel Iris: 14:41:00 INFO - Chipset Model: Intel Iris 14:41:00 INFO - Type: GPU 14:41:00 INFO - Bus: Built-In 14:41:00 INFO - VRAM (Dynamic, Max): 1536 MB 14:41:00 INFO - Vendor: Intel (0x8086) 14:41:00 INFO - Device ID: 0x0a2e 14:41:00 INFO - Revision ID: 0x0009 14:41:00 INFO - Displays: 14:41:00 INFO - Display: 14:41:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:41:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:41:00 INFO - Display Serial Number: 1600x1200 60 14:41:00 INFO - Main Display: Yes 14:41:00 INFO - Mirror: Off 14:41:00 INFO - Online: Yes 14:41:00 INFO - Rotation: Supported 14:41:00 INFO - Return code: 0 14:41:00 INFO - Running post-action listener: _resource_record_post_action 14:41:00 INFO - [mozharness: 2017-01-12 22:41:00.962831Z] Finished run-tests step (success) 14:41:00 INFO - Running post-run listener: _resource_record_post_run 14:41:01 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 958464; Write bytes: 652797440; Read time: 15; Write time: 1921 14:41:01 INFO - TinderboxPrint: CPU usage
53.5% 14:41:01 INFO - TinderboxPrint: I/O read bytes / time
958,464 / 15 14:41:01 INFO - TinderboxPrint: I/O write bytes / time
652,797,440 / 1,921 14:41:01 INFO - TinderboxPrint: CPU idle
2,251.4 (46.5%) 14:41:01 INFO - TinderboxPrint: CPU system
969.3 (20.0%) 14:41:01 INFO - TinderboxPrint: CPU user
1,620.9 (33.5%) 14:41:01 INFO - TinderboxPrint: Swap in / out
350,531,584 / 0 14:41:01 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 340761088; Write bytes: 334784512; Read time: 26553; Write time: 756 14:41:01 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 958464; Write bytes: 301338112; Read time: 15; Write time: 1113 14:41:01 INFO - Running post-run listener: _upload_blobber_files 14:41:01 INFO - Blob upload gear active. 14:41:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:41:01 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 14:41:01 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'] 14:41:01 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 14:41:01 INFO - (blobuploader) - INFO - Open directory for files ... 14:41:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:41:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:41:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:41:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:41:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:41:02 INFO - (blobuploader) - INFO - Done attempting. 14:41:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:41:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:41:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:41:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:41:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:41:03 INFO - (blobuploader) - INFO - Done attempting. 14:41:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:41:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:41:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:41:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:41:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:41:06 INFO - (blobuploader) - INFO - Done attempting. 14:41:06 INFO - (blobuploader) - INFO - Iteration through files over. 14:41:06 INFO - Return code: 0 14:41:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:41:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:41:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9c263bd5acf5a606ecc5535eb10116f474fd8ae756d1eb556fc4d0a3ce46ea7eb0e6a1d64124374d736a8028442a8819da573df503f3022af8f86e46d7c00f1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8dcfce5c9c8f95956a4da800973872e464c055af1ed0f42081686ddefa46eef1280e38720e38cfd84f9dd8aca34df9e585ebcff8d5d00f2d0a5cec1883890f82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b2cc027b33bcf005e3c6d8f80cb192f622e7bb6d16ad822b96146375d17281362d766b18c9420eb224ebb02958e391d063103d51276ed848ddd73804d313cd51"} 14:41:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:41:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:41:06 INFO - Contents: 14:41:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9c263bd5acf5a606ecc5535eb10116f474fd8ae756d1eb556fc4d0a3ce46ea7eb0e6a1d64124374d736a8028442a8819da573df503f3022af8f86e46d7c00f1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8dcfce5c9c8f95956a4da800973872e464c055af1ed0f42081686ddefa46eef1280e38720e38cfd84f9dd8aca34df9e585ebcff8d5d00f2d0a5cec1883890f82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b2cc027b33bcf005e3c6d8f80cb192f622e7bb6d16ad822b96146375d17281362d766b18c9420eb224ebb02958e391d063103d51276ed848ddd73804d313cd51"} 14:41:06 INFO - Running post-run listener: copy_logs_to_upload_dir 14:41:06 INFO - Copying logs to upload dir... 14:41:06 INFO - mkdir: /builds/slave/test/build/upload/logs 14:41:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.530044 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-12 14:41:06.138316) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:06.141760) ========= 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.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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/9c263bd5acf5a606ecc5535eb10116f474fd8ae756d1eb556fc4d0a3ce46ea7eb0e6a1d64124374d736a8028442a8819da573df503f3022af8f86e46d7c00f1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8dcfce5c9c8f95956a4da800973872e464c055af1ed0f42081686ddefa46eef1280e38720e38cfd84f9dd8aca34df9e585ebcff8d5d00f2d0a5cec1883890f82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b2cc027b33bcf005e3c6d8f80cb192f622e7bb6d16ad822b96146375d17281362d766b18c9420eb224ebb02958e391d063103d51276ed848ddd73804d313cd51"} build_url:https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010306 build_url: 'https://queue.taskcluster.net/v1/task/IxDTp5p8SE2jDLGmNRFpMg/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/9c263bd5acf5a606ecc5535eb10116f474fd8ae756d1eb556fc4d0a3ce46ea7eb0e6a1d64124374d736a8028442a8819da573df503f3022af8f86e46d7c00f1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8dcfce5c9c8f95956a4da800973872e464c055af1ed0f42081686ddefa46eef1280e38720e38cfd84f9dd8aca34df9e585ebcff8d5d00f2d0a5cec1883890f82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b2cc027b33bcf005e3c6d8f80cb192f622e7bb6d16ad822b96146375d17281362d766b18c9420eb224ebb02958e391d063103d51276ed848ddd73804d313cd51"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:06.180188) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:06.180568) ========= 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.qMpvaGFxEQ/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.Fv5qCslE9l/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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.004890 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:06.230943) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 14:41:06.231393) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 14:41:06.231788) ========= ========= Total master_lag: 0.19 =========