builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0390 starttime: 1484132369.74 results: success (0) buildid: 20170111004018 builduid: 0626c5a0890b4f0088069ee0fcd2b4e9 revision: 5cf9f0f65d201fc4631530c8f00e3a14887c1ad1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 02:59:29.740388) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 02:59:29.740833) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2017-01-11 02:59:29.741109) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2017-01-11 02:59:36.677791) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 02:59:36.678109) ========= 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.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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-11 02:59:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.7M=0.001s 2017-01-11 02:59:36 (17.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133592 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 02:59:36.845156) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 02:59:36.845554) ========= 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.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.024528 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 02:59:36.898379) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-11 02:59:36.900961) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5cf9f0f65d201fc4631530c8f00e3a14887c1ad1 --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 5cf9f0f65d201fc4631530c8f00e3a14887c1ad1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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-11 02:59:36,896 truncating revision to first 12 chars 2017-01-11 02:59:36,896 Setting DEBUG logging. 2017-01-11 02:59:36,897 attempt 1/10 2017-01-11 02:59:36,897 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5cf9f0f65d20?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-11 02:59:38,274 unpacking tar archive at: mozilla-aurora-5cf9f0f65d20/testing/mozharness/ program finished with exit code 0 elapsedTime=1.632356 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-11 02:59:38.556738) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 02:59:38.557123) ========= script_repo_revision: 5cf9f0f65d201fc4631530c8f00e3a14887c1ad1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 02:59:38.566662) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 02:59:38.567086) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 02:59:38.592222) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-01-11 02:59:38.592668) ========= /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.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:59:38 INFO - MultiFileLogger online at 20170111 02:59:38 in /builds/slave/test 02:59:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:59:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:59:38 INFO - {'append_to_log': False, 02:59:38 INFO - 'base_work_dir': '/builds/slave/test', 02:59:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:59:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:59:38 INFO - 'branch': 'Mozilla-Aurora', 02:59:38 INFO - 'buildbot_json_path': 'buildprops.json', 02:59:38 INFO - 'config_files': ('talos/mac_config.py',), 02:59:38 INFO - 'default_actions': ('clobber', 02:59:38 INFO - 'read-buildbot-config', 02:59:38 INFO - 'download-and-extract', 02:59:38 INFO - 'populate-webroot', 02:59:38 INFO - 'create-virtualenv', 02:59:38 INFO - 'install', 02:59:38 INFO - 'run-tests'), 02:59:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:59:38 INFO - 'download_minidump_stackwalk': True, 02:59:38 INFO - 'download_symbols': 'ondemand', 02:59:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:59:38 INFO - 'tooltool.py': '/tools/tooltool.py', 02:59:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:59:38 INFO - '/tools/misc-python/virtualenv.py')}, 02:59:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:59:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:59:38 INFO - 'installer_path': 'installer.exe', 02:59:38 INFO - 'log_level': 'info', 02:59:38 INFO - 'log_name': 'talos', 02:59:38 INFO - 'log_to_console': True, 02:59:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:59:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:59:38 INFO - 'opt_config_files': (), 02:59:38 INFO - 'pip_index': False, 02:59:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:59:38 INFO - 'cmd': ('bash', 02:59:38 INFO - '-c', 02:59:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:59:38 INFO - 'enabled': True, 02:59:38 INFO - 'halt_on_failure': False, 02:59:38 INFO - 'name': 'check_screen_resolution'},), 02:59:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:59:38 INFO - 'cmd': ('bash', 02:59:38 INFO - '-c', 02:59:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:59:38 INFO - 'enabled': True, 02:59:38 INFO - 'halt_on_failure': False, 02:59:38 INFO - 'name': 'check_screen_resolution'},), 02:59:38 INFO - 'run_cmd_checks_enabled': True, 02:59:38 INFO - 'sps_profile': False, 02:59:38 INFO - 'sps_profile_interval': 0, 02:59:38 INFO - 'suite': 'dromaeojs', 02:59:38 INFO - 'system_bits': '32', 02:59:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:59:38 INFO - 'title': 't-yosemite-r7-0390', 02:59:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:59:38 INFO - 'use_talos_json': True, 02:59:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:59:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:59:38 INFO - 'work_dir': 'build'} 02:59:38 INFO - [mozharness: 2017-01-11 10:59:38.692966Z] Running clobber step. 02:59:38 INFO - Running pre-action listener: _resource_record_pre_action 02:59:38 INFO - Running main action method: clobber 02:59:38 INFO - rmtree: /builds/slave/test/build 02:59:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:59:41 INFO - Running post-action listener: _resource_record_post_action 02:59:41 INFO - [mozharness: 2017-01-11 10:59:41.063634Z] Finished clobber step (success) 02:59:41 INFO - [mozharness: 2017-01-11 10:59:41.063757Z] Running read-buildbot-config step. 02:59:41 INFO - Running pre-action listener: _resource_record_pre_action 02:59:41 INFO - Running main action method: read_buildbot_config 02:59:41 INFO - Using buildbot properties: 02:59:41 INFO - { 02:59:41 INFO - "project": "", 02:59:41 INFO - "product": "firefox", 02:59:41 INFO - "script_repo_revision": "production", 02:59:41 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:59:41 INFO - "repository": "", 02:59:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:59:41 INFO - "buildid": "20170111004018", 02:59:41 INFO - "stage_platform": "macosx64", 02:59:41 INFO - "basedir": "/builds/slave/test", 02:59:41 INFO - "nightly_build": "True", 02:59:41 INFO - "buildnumber": 17, 02:59:41 INFO - "slavename": "t-yosemite-r7-0390", 02:59:41 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:59:41 INFO - "platform": "yosemite_r7", 02:59:41 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:59:41 INFO - "branch": "mozilla-aurora", 02:59:41 INFO - "repo_path": "releases/mozilla-aurora", 02:59:41 INFO - "revision": "5cf9f0f65d201fc4631530c8f00e3a14887c1ad1", 02:59:41 INFO - "pgo_build": "False", 02:59:41 INFO - "builduid": "0626c5a0890b4f0088069ee0fcd2b4e9", 02:59:41 INFO - "slavebuilddir": "test" 02:59:41 INFO - } 02:59:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:59:41 INFO - Running post-action listener: _resource_record_post_action 02:59:41 INFO - [mozharness: 2017-01-11 10:59:41.067159Z] Finished read-buildbot-config step (success) 02:59:41 INFO - [mozharness: 2017-01-11 10:59:41.067251Z] Running download-and-extract step. 02:59:41 INFO - Running pre-action listener: _resource_record_pre_action 02:59:41 INFO - Running main action method: download_and_extract 02:59:41 INFO - mkdir: /builds/slave/test/build/tests 02:59: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')]} 02:59:41 INFO - trying https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:59:41 INFO - Downloading https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:59:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:59:41 INFO - Downloaded 1199 bytes. 02:59:41 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:59:41 INFO - Using the following test package requirements: 02:59:41 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:59:41 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:59:41 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:59:41 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:59:41 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:59:41 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:59:41 INFO - u'jsshell-mac.zip'], 02:59:41 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:59:41 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:59:41 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:59:41 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:59:41 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:59:41 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:59:41 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:59:41 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:59:41 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:59:41 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:59:41 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:59:41 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:59:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:59:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:59:41 INFO - Fetch https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:59:42 INFO - Http code: 200 02:59:42 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 02:59:42 INFO - x-amz-cf-id: _roH-InZj15ArURAeUkGVMfI8xNrfDI27YGpOybs3GhQLrk6GfzARw== 02:59:42 INFO - x-amz-version-id: af4B8GKUSHw1mvWFTal9tsRaRhB3xy5S 02:59:42 INFO - Expected file size: 16271760 02:59:42 INFO - Obtained file size: 16271760 02:59:49 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:59:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:59:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:59:49 INFO - Fetch https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:59:50 INFO - Http code: 200 02:59:50 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 02:59:50 INFO - x-amz-cf-id: rZSOkQyVWR7QEFKO-XU0kCS1bh14UtlItY6tjGQOgpevj8VmYDeqMA== 02:59:50 INFO - x-amz-version-id: af4B8GKUSHw1mvWFTal9tsRaRhB3xy5S 02:59:50 INFO - Expected file size: 16271760 02:59:50 INFO - Obtained file size: 16271760 02:59:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:59:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:59:56 INFO - Fetch https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:59:57 INFO - Http code: 200 02:59:57 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 02:59:57 INFO - x-amz-cf-id: y6AyCKQUxONERxC0KTLnotAtEkU126HPloQZ2jNaBusU_z88TYTIbg== 02:59:57 INFO - x-amz-version-id: Cdgw8xjTAGEQOVsX48QKoMT6_mlFSB7c 02:59:58 INFO - Expected file size: 14409038 02:59:58 INFO - Obtained file size: 14409038 02:59:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:59:58 INFO - trying https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:59:58 INFO - Downloading https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:59:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/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 03:00:01 INFO - Downloaded 109991314 bytes. 03:00:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:00:01 INFO - mkdir: /builds/slave/test/properties 03:00:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:00:01 INFO - Writing to file /builds/slave/test/properties/build_url 03:00:01 INFO - Contents: 03:00:01 INFO - build_url:https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:00:02 INFO - Running post-action listener: _resource_record_post_action 03:00:02 INFO - Running post-action listener: set_extra_try_arguments 03:00:02 INFO - [mozharness: 2017-01-11 11:00:02.693207Z] Finished download-and-extract step (success) 03:00:02 INFO - [mozharness: 2017-01-11 11:00:02.693343Z] Running populate-webroot step. 03:00:02 INFO - Running pre-action listener: _resource_record_pre_action 03:00:02 INFO - Running main action method: populate_webroot 03:00:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:00:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:00:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:00:02 INFO - '--tppagecycles', 03:00:02 INFO - '7'], 03:00:02 INFO - 'tests': ['tsvgm']}}, 03:00:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:00:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:00:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:00:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:00:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:00:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:00:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:02 INFO - 'talos_options': ['--disable-e10s'], 03:00:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:02 INFO - 'talos_options': ['--disable-e10s'], 03:00:02 INFO - 'tests': ['damp', 'tps']}, 03:00:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:02 INFO - 'tests': ['damp', 'tps']}, 03:00:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:00:02 INFO - 'tests': ['dromaeo_dom']}, 03:00:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:00:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:00:02 INFO - 'tests': ['basic_compositor_video']}, 03:00:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:00:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:00:02 INFO - 'tests': ['a11yr', 03:00:02 INFO - 'ts_paint', 03:00:02 INFO - 'tpaint', 03:00:02 INFO - 'sessionrestore', 03:00:02 INFO - 'sessionrestore_no_auto_restore', 03:00:02 INFO - 'tabpaint']}, 03:00:02 INFO - 'other-e10s': {'tests': ['a11yr', 03:00:02 INFO - 'ts_paint', 03:00:02 INFO - 'tpaint', 03:00:02 INFO - 'sessionrestore', 03:00:02 INFO - 'sessionrestore_no_auto_restore', 03:00:02 INFO - 'tabpaint']}, 03:00:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:00:02 INFO - 'ts_paint', 03:00:02 INFO - 'tpaint', 03:00:02 INFO - 'sessionrestore', 03:00:02 INFO - 'sessionrestore_no_auto_restore', 03:00:02 INFO - 'tabpaint']}, 03:00:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:00:02 INFO - 'ts_paint', 03:00:02 INFO - 'tpaint', 03:00:02 INFO - 'sessionrestore', 03:00:02 INFO - 'sessionrestore_no_auto_restore', 03:00:02 INFO - 'tabpaint']}, 03:00:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:00:02 INFO - 'tests': ['a11yr', 03:00:02 INFO - 'ts_paint', 03:00:02 INFO - 'tpaint', 03:00:02 INFO - 'sessionrestore', 03:00:02 INFO - 'sessionrestore_no_auto_restore', 03:00:02 INFO - 'tabpaint']}, 03:00:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:00:02 INFO - 'tests': ['a11yr', 03:00:02 INFO - 'ts_paint', 03:00:02 INFO - 'tpaint', 03:00:02 INFO - 'sessionrestore', 03:00:02 INFO - 'sessionrestore_no_auto_restore', 03:00:02 INFO - 'tabpaint']}, 03:00:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:00:02 INFO - 'tests': ['tsvgx', 03:00:02 INFO - 'tsvgr_opacity', 03:00:02 INFO - 'tart', 03:00:02 INFO - 'tscrollx', 03:00:02 INFO - 'cart']}, 03:00:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:00:02 INFO - 'tsvgr_opacity', 03:00:02 INFO - 'tart', 03:00:02 INFO - 'tscrollx', 03:00:02 INFO - 'cart']}, 03:00:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:02 INFO - 'talos_options': ['--disable-e10s'], 03:00:02 INFO - 'tests': ['tp5o']}, 03:00:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:02 INFO - 'tests': ['tp5o']}, 03:00:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:02 INFO - 'talos_options': ['--disable-e10s', 03:00:02 INFO - '--xperf_path', 03:00:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:02 INFO - 'tests': ['tp5n']}, 03:00:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:02 INFO - 'talos_options': ['--xperf_path', 03:00:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:02 INFO - 'tests': ['tp5n']}}, 03:00:02 INFO - 'talos.zip': {'path': '', 03:00:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:00:02 INFO - Running post-action listener: _resource_record_post_action 03:00:02 INFO - [mozharness: 2017-01-11 11:00:02.712475Z] Finished populate-webroot step (success) 03:00:02 INFO - [mozharness: 2017-01-11 11:00:02.712568Z] Running create-virtualenv step. 03:00:02 INFO - Running pre-action listener: _resource_record_pre_action 03:00:02 INFO - Running main action method: create_virtualenv 03:00:02 INFO - Creating virtualenv /builds/slave/test/build/venv 03:00:02 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 03:00:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:00:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:00:02 INFO - Using real prefix '/tools/python27' 03:00:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:00:03 INFO - Installing distribute.............................................................................................................................................................................................done. 03:00:06 INFO - Installing pip.................done. 03:00:06 INFO - Return code: 0 03:00:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:00:06 INFO - Reading from file tmpfile_stdout 03:00:06 INFO - Output received: 03:00:06 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) 03:00:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dffc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df72570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d7855e2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de10d30>, '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 0x10dc76db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de08b20>, '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.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:00:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:00:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6ZzvYVZBO/Render', 03:00:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:06 INFO - 'HOME': '/Users/cltbld', 03:00:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:06 INFO - 'LOGNAME': 'cltbld', 03:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:06 INFO - 'MOZ_NO_REMOTE': '1', 03:00:06 INFO - 'NO_EM_RESTART': '1', 03:00:06 INFO - 'PAGER': '/bin/cat', 03:00:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:06 INFO - 'PWD': '/builds/slave/test', 03:00:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:06 INFO - 'SHELL': '/bin/bash', 03:00:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OcVQqzqZQN/Listeners', 03:00:06 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:00:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:06 INFO - 'USER': 'cltbld', 03:00:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:06 INFO - 'XPC_FLAGS': '0x0', 03:00:06 INFO - 'XPC_SERVICE_NAME': '0', 03:00:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:06 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 03:00:06 INFO - Cleaning up... 03:00:06 INFO - Return code: 0 03:00:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dffc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df72570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d7855e2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de10d30>, '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 0x10dc76db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de08b20>, '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.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:00:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:00:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6ZzvYVZBO/Render', 03:00:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:06 INFO - 'HOME': '/Users/cltbld', 03:00:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:06 INFO - 'LOGNAME': 'cltbld', 03:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:06 INFO - 'MOZ_NO_REMOTE': '1', 03:00:06 INFO - 'NO_EM_RESTART': '1', 03:00:06 INFO - 'PAGER': '/bin/cat', 03:00:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:06 INFO - 'PWD': '/builds/slave/test', 03:00:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:06 INFO - 'SHELL': '/bin/bash', 03:00:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OcVQqzqZQN/Listeners', 03:00:06 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:00:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:06 INFO - 'USER': 'cltbld', 03:00:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:06 INFO - 'XPC_FLAGS': '0x0', 03:00:06 INFO - 'XPC_SERVICE_NAME': '0', 03:00:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:07 INFO - Downloading/unpacking psutil>=3.1.1 03:00:07 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 03:00:07 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 03:00:07 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 03:00:07 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 03:00:07 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 03:00:07 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 03:00:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:00:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:00:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:00:10 INFO - Installing collected packages: psutil 03:00:10 INFO - Running setup.py install for psutil 03:00:10 INFO - building 'psutil._psutil_osx' extension 03:00:10 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 03:00:11 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 03:00:11 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 03:00:11 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 03:00:11 INFO - building 'psutil._psutil_posix' extension 03:00:11 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 03:00:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:00:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:00:11 INFO - ^ 03:00:11 INFO - 1 warning generated. 03:00:11 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 03:00:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:00:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:00:11 INFO - Successfully installed psutil 03:00:11 INFO - Cleaning up... 03:00:11 INFO - Return code: 0 03:00:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:00: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')]} 03:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00: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')]} 03:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:11 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 0x10dffc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df72570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d7855e2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de10d30>, '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 0x10dc76db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de08b20>, '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.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:00:11 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 03:00:11 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 03:00:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6ZzvYVZBO/Render', 03:00:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:11 INFO - 'HOME': '/Users/cltbld', 03:00:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:11 INFO - 'LOGNAME': 'cltbld', 03:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:11 INFO - 'MOZ_NO_REMOTE': '1', 03:00:11 INFO - 'NO_EM_RESTART': '1', 03:00:11 INFO - 'PAGER': '/bin/cat', 03:00:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:11 INFO - 'PWD': '/builds/slave/test', 03:00:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:11 INFO - 'SHELL': '/bin/bash', 03:00:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OcVQqzqZQN/Listeners', 03:00:11 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:00:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:11 INFO - 'USER': 'cltbld', 03:00:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:11 INFO - 'XPC_FLAGS': '0x0', 03:00:11 INFO - 'XPC_SERVICE_NAME': '0', 03:00:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:00:14 INFO - Installing collected packages: mozsystemmonitor 03:00:14 INFO - Running setup.py install for mozsystemmonitor 03:00:15 INFO - Successfully installed mozsystemmonitor 03:00:15 INFO - Cleaning up... 03:00:15 INFO - Return code: 0 03:00:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:15 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 0x10dffc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df72570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d7855e2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de10d30>, '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 0x10dc76db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de08b20>, '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.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:00:15 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 03:00:15 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 03:00:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6ZzvYVZBO/Render', 03:00:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:15 INFO - 'HOME': '/Users/cltbld', 03:00:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:15 INFO - 'LOGNAME': 'cltbld', 03:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:15 INFO - 'MOZ_NO_REMOTE': '1', 03:00:15 INFO - 'NO_EM_RESTART': '1', 03:00:15 INFO - 'PAGER': '/bin/cat', 03:00:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:15 INFO - 'PWD': '/builds/slave/test', 03:00:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:15 INFO - 'SHELL': '/bin/bash', 03:00:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OcVQqzqZQN/Listeners', 03:00:15 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:00:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:15 INFO - 'USER': 'cltbld', 03:00:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:15 INFO - 'XPC_FLAGS': '0x0', 03:00:15 INFO - 'XPC_SERVICE_NAME': '0', 03:00:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:16 INFO - Downloading/unpacking jsonschema==2.5.1 03:00:16 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 03:00:16 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 03:00:16 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 03:00:16 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 03:00:16 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 03:00:16 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 03:00:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:00:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:00: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 03:00: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 03:00: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 03:00: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 03:00: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 03:00: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 03:00:19 INFO - Downloading functools32-3.2.3-2.tar.gz 03:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:00:19 INFO - warning: no files found matching '*.txt' 03:00:19 INFO - no previously-included directories found matching 'build' 03:00:19 INFO - no previously-included directories found matching 'dist' 03:00:19 INFO - no previously-included directories found matching '.git*' 03:00:19 INFO - Installing collected packages: jsonschema, functools32 03:00:19 INFO - Running setup.py install for functools32 03:00:19 INFO - warning: no files found matching '*.txt' 03:00:19 INFO - no previously-included directories found matching 'build' 03:00:19 INFO - no previously-included directories found matching 'dist' 03:00:19 INFO - no previously-included directories found matching '.git*' 03:00:19 INFO - Successfully installed jsonschema functools32 03:00:19 INFO - Cleaning up... 03:00:19 INFO - Return code: 0 03:00:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:00: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')]} 03:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00: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')]} 03:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00: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', '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 0x10dffc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df72570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d7855e2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de10d30>, '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 0x10dc76db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de08b20>, '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.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:00: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:00: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 functools32==3.2.3-2 03:00:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6ZzvYVZBO/Render', 03:00:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:19 INFO - 'HOME': '/Users/cltbld', 03:00:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:19 INFO - 'LOGNAME': 'cltbld', 03:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:19 INFO - 'MOZ_NO_REMOTE': '1', 03:00:19 INFO - 'NO_EM_RESTART': '1', 03:00:19 INFO - 'PAGER': '/bin/cat', 03:00:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:19 INFO - 'PWD': '/builds/slave/test', 03:00:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:19 INFO - 'SHELL': '/bin/bash', 03:00:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OcVQqzqZQN/Listeners', 03:00:19 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:00:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:19 INFO - 'USER': 'cltbld', 03:00:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:19 INFO - 'XPC_FLAGS': '0x0', 03:00:19 INFO - 'XPC_SERVICE_NAME': '0', 03:00:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:00:20 INFO - Cleaning up... 03:00:20 INFO - Return code: 0 03:00:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:00:20 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')]} 03:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:20 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')]} 03:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:20 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 0x10dffc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df72570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d7855e2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de10d30>, '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 0x10dc76db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de08b20>, '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.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:00:20 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 03:00:20 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 03:00:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6ZzvYVZBO/Render', 03:00:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:20 INFO - 'HOME': '/Users/cltbld', 03:00:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:20 INFO - 'LOGNAME': 'cltbld', 03:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:20 INFO - 'MOZ_NO_REMOTE': '1', 03:00:20 INFO - 'NO_EM_RESTART': '1', 03:00:20 INFO - 'PAGER': '/bin/cat', 03:00:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:20 INFO - 'PWD': '/builds/slave/test', 03:00:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:20 INFO - 'SHELL': '/bin/bash', 03:00:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OcVQqzqZQN/Listeners', 03:00:20 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:00:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:20 INFO - 'USER': 'cltbld', 03:00:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:20 INFO - 'XPC_FLAGS': '0x0', 03:00:20 INFO - 'XPC_SERVICE_NAME': '0', 03:00:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:20 INFO - Downloading/unpacking blobuploader==1.2.4 03:00:20 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 03:00:20 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 03:00:20 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 03:00:20 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 03:00:20 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 03:00:20 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 03:00:23 INFO - Downloading blobuploader-1.2.4.tar.gz 03:00:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:00:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:00:23 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 03:00:23 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 03:00:23 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 03:00:23 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 03:00:23 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 03:00:23 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 03:00:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:00:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:00:23 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 03:00:23 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 03:00:23 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 03:00:23 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 03:00:23 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 03:00:23 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 03:00:24 INFO - Downloading docopt-0.6.1.tar.gz 03:00:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:00:24 INFO - Installing collected packages: blobuploader, requests, docopt 03:00:24 INFO - Running setup.py install for blobuploader 03:00:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:00:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:00:24 INFO - Running setup.py install for requests 03:00:25 INFO - Running setup.py install for docopt 03:00:25 INFO - Successfully installed blobuploader requests docopt 03:00:25 INFO - Cleaning up... 03:00:25 INFO - Return code: 0 03:00:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:00: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')]} 03:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00: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')]} 03:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:25 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 0x10dffc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df72570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d7855e2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de10d30>, '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 0x10dc76db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de08b20>, '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.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:00:25 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 03:00:25 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 03:00:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6ZzvYVZBO/Render', 03:00:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:25 INFO - 'HOME': '/Users/cltbld', 03:00:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:25 INFO - 'LOGNAME': 'cltbld', 03:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:25 INFO - 'MOZ_NO_REMOTE': '1', 03:00:25 INFO - 'NO_EM_RESTART': '1', 03:00:25 INFO - 'PAGER': '/bin/cat', 03:00:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:25 INFO - 'PWD': '/builds/slave/test', 03:00:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:25 INFO - 'SHELL': '/bin/bash', 03:00:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OcVQqzqZQN/Listeners', 03:00:25 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:00:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:25 INFO - 'USER': 'cltbld', 03:00:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:25 INFO - 'XPC_FLAGS': '0x0', 03:00:25 INFO - 'XPC_SERVICE_NAME': '0', 03:00:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:00:25 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-zaqhNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:00:25 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-7wM1rV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:00:25 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-l1RjcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:00:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-pxl6ms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:00:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-nh7Nzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:00:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-6FBUJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:00:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-x7jDmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:00:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-ETR2qG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:00:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-WPyE7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:00:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-mPrIKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:00:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Ds4764-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:00:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-6vgpra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:00:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-U9i6fV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:00:27 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-CsS8Qs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:27 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-fe4Z9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:00:27 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-bWexMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:00:27 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-O_IRjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:00:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:00:27 INFO - Running setup.py install for manifestparser 03:00:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:00:27 INFO - Running setup.py install for mozcrash 03:00:27 INFO - Running setup.py install for mozdebug 03:00:27 INFO - Running setup.py install for mozdevice 03:00:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:00:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:00:28 INFO - Running setup.py install for mozfile 03:00:28 INFO - Running setup.py install for mozhttpd 03:00:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:00:28 INFO - Running setup.py install for mozinfo 03:00:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:00:28 INFO - Running setup.py install for mozInstall 03:00:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:00:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:00:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:00:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:00:28 INFO - Running setup.py install for mozleak 03:00:28 INFO - Running setup.py install for mozlog 03:00:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:00:29 INFO - Running setup.py install for moznetwork 03:00:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:00:29 INFO - Running setup.py install for mozprocess 03:00:29 INFO - Running setup.py install for mozprofile 03:00:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:00:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:00:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:00:29 INFO - Running setup.py install for mozrunner 03:00:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:00:29 INFO - Running setup.py install for mozscreenshot 03:00:29 INFO - Running setup.py install for moztest 03:00:29 INFO - Running setup.py install for mozversion 03:00:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:00:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:00:30 INFO - Cleaning up... 03:00:30 INFO - Return code: 0 03:00:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:00:30 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')]} 03:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:30 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')]} 03:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:30 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 0x10dffc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df72570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d7855e2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de10d30>, '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 0x10dc76db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de08b20>, '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.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:00:30 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 03:00:30 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 03:00:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6ZzvYVZBO/Render', 03:00:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:30 INFO - 'HOME': '/Users/cltbld', 03:00:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:30 INFO - 'LOGNAME': 'cltbld', 03:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:30 INFO - 'MOZ_NO_REMOTE': '1', 03:00:30 INFO - 'NO_EM_RESTART': '1', 03:00:30 INFO - 'PAGER': '/bin/cat', 03:00:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:30 INFO - 'PWD': '/builds/slave/test', 03:00:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:30 INFO - 'SHELL': '/bin/bash', 03:00:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OcVQqzqZQN/Listeners', 03:00:30 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:00:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:30 INFO - 'USER': 'cltbld', 03:00:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:30 INFO - 'XPC_FLAGS': '0x0', 03:00:30 INFO - 'XPC_SERVICE_NAME': '0', 03:00:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:00:30 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-xY3twy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:00:30 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)) 03:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:00:30 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-KoJlMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:00:30 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)) 03:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:00:30 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-RyOzL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:00:30 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)) 03:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:00:30 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-k4b2h0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:00:30 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)) 03:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:00:30 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-rqCLuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:00:30 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)) 03:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:00:30 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-9sjx7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:00:31 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)) 03:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:00:31 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-n4XqDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:00:31 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)) 03:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:00:31 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-4wTgyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:00:31 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)) 03:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:00:31 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-ygO7kA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:00:31 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)) 03:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:00:31 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-TDSlTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:00:31 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)) 03:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:00:31 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-OQCZON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:00:31 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)) 03:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:00:31 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-WxNcRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:00:31 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)) 03:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:00:31 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-zdXy_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:00:31 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)) 03:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:00:31 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-2qGDAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:00:31 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)) 03:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:31 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-CYnKMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:31 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)) 03:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:00:32 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-kEBDQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:00:32 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)) 03:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:00:32 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-yV0InN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:00:32 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)) 03:00:32 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)) 03:00:32 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)) 03:00:32 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)) 03:00:32 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)) 03:00:32 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)) 03:00:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:00: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 03:00: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 03:00: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 03:00: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 03:00: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 03:00: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 03:00:34 INFO - Downloading blessings-1.6.tar.gz 03:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:00:35 INFO - Installing collected packages: blessings 03:00:35 INFO - Running setup.py install for blessings 03:00:35 INFO - Successfully installed blessings 03:00:35 INFO - Cleaning up... 03:00:35 INFO - Return code: 0 03:00:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:00:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:00:35 INFO - Reading from file tmpfile_stdout 03:00:35 INFO - Current package versions: 03:00:35 INFO - blessings == 1.6 03:00:35 INFO - blobuploader == 1.2.4 03:00:35 INFO - docopt == 0.6.1 03:00:35 INFO - functools32 == 3.2.3-2 03:00:35 INFO - jsonschema == 2.5.1 03:00:35 INFO - manifestparser == 1.1 03:00:35 INFO - mozInstall == 1.12 03:00:35 INFO - mozcrash == 1.0 03:00:35 INFO - mozdebug == 0.1 03:00:35 INFO - mozdevice == 0.48 03:00:35 INFO - mozfile == 1.2 03:00:35 INFO - mozhttpd == 0.7 03:00:35 INFO - mozinfo == 0.9 03:00:35 INFO - mozleak == 0.1 03:00:35 INFO - mozlog == 3.4 03:00:35 INFO - moznetwork == 0.27 03:00:35 INFO - mozprocess == 0.23 03:00:35 INFO - mozprofile == 0.28 03:00:35 INFO - mozrunner == 6.13 03:00:35 INFO - mozscreenshot == 0.1 03:00:35 INFO - mozsystemmonitor == 0.3 03:00:35 INFO - moztest == 0.8 03:00:35 INFO - mozversion == 1.4 03:00:35 INFO - psutil == 3.1.1 03:00:35 INFO - requests == 1.2.3 03:00:35 INFO - wsgiref == 0.1.2 03:00:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:35 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 0x10dffc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df72570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d7855e2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de10d30>, '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 0x10dc76db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de08b20>, '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.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:00:35 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 03:00:35 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 03:00:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6ZzvYVZBO/Render', 03:00:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:35 INFO - 'HOME': '/Users/cltbld', 03:00:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:35 INFO - 'LOGNAME': 'cltbld', 03:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:35 INFO - 'MOZ_NO_REMOTE': '1', 03:00:35 INFO - 'NO_EM_RESTART': '1', 03:00:35 INFO - 'PAGER': '/bin/cat', 03:00:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:35 INFO - 'PWD': '/builds/slave/test', 03:00:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:35 INFO - 'SHELL': '/bin/bash', 03:00:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OcVQqzqZQN/Listeners', 03:00:35 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:00:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:35 INFO - 'USER': 'cltbld', 03:00:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:35 INFO - 'XPC_FLAGS': '0x0', 03:00:35 INFO - 'XPC_SERVICE_NAME': '0', 03:00:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:35 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)) 03:00:35 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)) 03:00:35 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)) 03:00:35 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)) 03:00:35 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)) 03:00:35 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)) 03:00:35 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)) 03:00:35 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)) 03:00:35 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)) 03:00:35 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)) 03:00:35 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)) 03:00:35 INFO - Cleaning up... 03:00:35 INFO - Return code: 0 03:00:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:35 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 0x10dffc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df72570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d7855e2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de10d30>, '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 0x10dc76db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de08b20>, '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.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:00:35 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 03:00:35 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 03:00:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6ZzvYVZBO/Render', 03:00:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:35 INFO - 'HOME': '/Users/cltbld', 03:00:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:35 INFO - 'LOGNAME': 'cltbld', 03:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:35 INFO - 'MOZ_NO_REMOTE': '1', 03:00:35 INFO - 'NO_EM_RESTART': '1', 03:00:35 INFO - 'PAGER': '/bin/cat', 03:00:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:35 INFO - 'PWD': '/builds/slave/test', 03:00:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:35 INFO - 'SHELL': '/bin/bash', 03:00:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OcVQqzqZQN/Listeners', 03:00:35 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:00:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:35 INFO - 'USER': 'cltbld', 03:00:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:35 INFO - 'XPC_FLAGS': '0x0', 03:00:35 INFO - 'XPC_SERVICE_NAME': '0', 03:00:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:00:36 INFO - Cleaning up... 03:00:36 INFO - Return code: 0 03:00:36 INFO - Running post-action listener: _resource_record_post_action 03:00:36 INFO - Running post-action listener: _start_resource_monitoring 03:00:36 INFO - Starting resource monitoring. 03:00:36 INFO - [mozharness: 2017-01-11 11:00:36.191751Z] Finished create-virtualenv step (success) 03:00:36 INFO - [mozharness: 2017-01-11 11:00:36.192809Z] Running install step. 03:00:36 INFO - Running pre-action listener: _resource_record_pre_action 03:00:36 INFO - Running main action method: install 03:00:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:00:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:00:36 INFO - Reading from file tmpfile_stdout 03:00:36 INFO - Detecting whether we're running mozinstall >=1.0... 03:00:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:00:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:00:36 INFO - Reading from file tmpfile_stdout 03:00:36 INFO - Output received: 03:00:36 INFO - Usage: mozinstall [options] installer 03:00:36 INFO - Options: 03:00:36 INFO - -h, --help show this help message and exit 03:00:36 INFO - -d DEST, --destination=DEST 03:00:36 INFO - Directory to install application into. [default: 03:00:36 INFO - "/builds/slave/test"] 03:00:36 INFO - --app=APP Application being installed. [default: firefox] 03:00:36 INFO - mkdir: /builds/slave/test/build/application 03:00:36 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'] 03:00:36 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 03:01:07 INFO - Reading from file tmpfile_stdout 03:01:07 INFO - Output received: 03:01:07 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:01:07 INFO - Running post-action listener: _resource_record_post_action 03:01:07 INFO - [mozharness: 2017-01-11 11:01:07.205505Z] Finished install step (success) 03:01:07 INFO - [mozharness: 2017-01-11 11:01:07.205700Z] Running run-tests step. 03:01:07 INFO - Running pre-action listener: _resource_record_pre_action 03:01:07 INFO - Running main action method: run_tests 03:01:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:01:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:01:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:01:07 INFO - 2017-01-11 03:01:07.220 screenresolution[1637:7490] starting screenresolution argv=screenresolution get 03:01:07 INFO - 2017-01-11 03:01:07.222 screenresolution[1637:7490] Display 0: 1600x1200x32@60 03:01:07 INFO - 2017-01-11 03:01:07.228 screenresolution[1638:7493] starting screenresolution argv=screenresolution list 03:01:07 INFO - Available Modes on Display 0 03:01:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:01:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:01:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:01:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:01:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:01:07 INFO - Intel Iris: 03:01:07 INFO - Chipset Model: Intel Iris 03:01:07 INFO - Type: GPU 03:01:07 INFO - Bus: Built-In 03:01:07 INFO - VRAM (Dynamic, Max): 1536 MB 03:01:07 INFO - Vendor: Intel (0x8086) 03:01:07 INFO - Device ID: 0x0a2e 03:01:07 INFO - Revision ID: 0x0009 03:01:07 INFO - Displays: 03:01:07 INFO - Display: 03:01:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:01:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:01:07 INFO - Display Serial Number: 1600x1200 60 03:01:07 INFO - Main Display: Yes 03:01:07 INFO - Mirror: Off 03:01:07 INFO - Online: Yes 03:01:07 INFO - Rotation: Supported 03:01:07 INFO - Return code: 0 03:01:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:01:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:01:07 INFO - Python 2.7.3 03:01:07 INFO - Return code: 0 03:01:07 INFO - grabbing minidump binary from tooltool 03:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:07 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 0x10de10d30>, '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 0x10dc76db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de08b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:01:07 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 03:01:07 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 03:01:07 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 03:01:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:01:07 INFO - Return code: 0 03:01:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:01:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:01:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:01:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:01:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:01:07 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/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 03:01:07 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/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0390 --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 03:01:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6ZzvYVZBO/Render', 03:01:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:07 INFO - 'HOME': '/Users/cltbld', 03:01:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:07 INFO - 'LOGNAME': 'cltbld', 03:01:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:01:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:07 INFO - 'MOZ_NO_REMOTE': '1', 03:01:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:01:07 INFO - 'NO_EM_RESTART': '1', 03:01:07 INFO - 'PAGER': '/bin/cat', 03:01:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:07 INFO - 'PWD': '/builds/slave/test', 03:01:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:01:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:07 INFO - 'SHELL': '/bin/bash', 03:01:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OcVQqzqZQN/Listeners', 03:01:07 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:01:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:07 INFO - 'USER': 'cltbld', 03:01:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:07 INFO - 'XPC_FLAGS': '0x0', 03:01:07 INFO - 'XPC_SERVICE_NAME': '0', 03:01:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:07 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/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 03:01:07 INFO - mozversion application_buildid: 20170111004018 03:01:07 INFO - mozversion application_changeset: 5cf9f0f65d201fc4631530c8f00e3a14887c1ad1 03:01:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:01:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:01:07 INFO - mozversion application_name: Firefox 03:01:07 INFO - mozversion application_remotingname: firefox-dev 03:01:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:01:07 INFO - mozversion application_vendor: Mozilla 03:01:07 INFO - mozversion application_version: 52.0a2 03:01:07 INFO - mozversion platform_buildid: 20170111004018 03:01:07 INFO - mozversion platform_changeset: 5cf9f0f65d201fc4631530c8f00e3a14887c1ad1 03:01:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:01:07 INFO - mozversion platform_version: 52.0a2 03:01:07 INFO - using testdate: 1484132467 03:01:07 INFO - actual date: 1484132467 03:01:07 INFO - starting webserver on 'localhost:49235' 03:01:07 INFO - SUITE-START | Running 2 tests 03:01:07 INFO - TEST-START | dromaeo_css 03:01:07 INFO - operating with platform_type : mac_ 03:01:07 INFO - Initialising browser for dromaeo_css test... 03:01:07 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpvUoxxk/profile http://localhost:49235/getInfo.html) 03:01:10 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 03:01:10 INFO - PROCESS | 1648 | colorDepth:24 03:01:10 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 03:01:10 INFO - PROCESS | 1648 | __metrics 03:01:11 INFO - PROCESS | 1648 | 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" 03:01:11 INFO - PROCESS | 1648 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:01:11 INFO - TEST-INFO | 1648: exit 0 03:01:11 INFO - Browser initialized. 03:01:11 INFO - Running cycle 1/1 for dromaeo_css test... 03:01:11 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpvUoxxk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:01:24 INFO - PROCESS | 1655 | RSS: Main: 222339072 03:01:24 INFO - PROCESS | 1655 | 03:04:27 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 03:04:30 INFO - PROCESS | 1655 | RSS: Main: 387567616 03:04:30 INFO - PROCESS | 1655 | 03:07:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 03:07:33 INFO - PROCESS | 1655 | RSS: Main: 346472448 03:07:33 INFO - PROCESS | 1655 | 03:10:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 03:10:36 INFO - PROCESS | 1655 | RSS: Main: 319832064 03:10:36 INFO - PROCESS | 1655 | 03:13:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 03:13:39 INFO - PROCESS | 1655 | RSS: Main: 329199616 03:13:39 INFO - PROCESS | 1655 | 03:16:43 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 03:16:43 INFO - PROCESS | 1655 | RSS: Main: 313085952 03:16:43 INFO - PROCESS | 1655 | 03:19:46 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 03:19:47 INFO - PROCESS | 1655 | RSS: Main: 393416704 03:19:47 INFO - PROCESS | 1655 | 03:19:47 INFO - PROCESS | 1655 | __start_tp_report 03:19:47 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 03:19:47 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 03:19:47 INFO - PROCESS | 1655 | |i|pagename|runs| 03:19:47 INFO - PROCESS | 1655 | |0;dojo.html;2805.2145399288192;3257.4299497587917;3274.2796584751345;3288.9968559589315;3316.6683331666836;5522.668639881605;5551.500364967163;5577.191307260445;5589.245451863997;5599.132134519145;5555.416681248461;5612.550995920326;5617.887642247153;5653;5695.345035320939;5112.488751124895;5114.693118412889;5117.692938423706;5182.248573956774;5254.290670759437;4442.777861106948;5040.747962601874;5054.21659642756;5111.207762796773;5147.7426128693605;5118.360204974379;5122.667026643257;5156.561692256163;5171.0692075426405;5183.792648294064;5136.768845401966;5172.793088276464;5193.039287731782;5203.115470370046;5234;5091.338126043591;5156.381234251876;5198.376194856603;5207.010667973111;5256.106461901448;4639.791209395586;5437.456254374571;5498.367687715924;5506.366767821672;5548.805791797308;6048.606840555394;6063.423974722395;6084.452399284086;6088.786892458743;6103.23709536308;6202.19371481709;6217.720202590895;6227.626342419469;6252.562320637558;6265.906011409833;5574.331080270394;5620.241267428844;5640.379558248589;5642.802501912413;5752.6548407096125;6248.5313601480075;6260.248770147611;6266.216722909637;6293.2762732285455;6301.40136687014;6044.274687037496;6046.758129674852;6057.3336932937345;6060.33336333003;6067.969660151671;6030.517558595302;6056.3338032816355;6145.480353055315;6160.661163636043;6204.5967012144065;6051.546134039877;6079.148919151234;6118.800145424834;6133.65167052099;6205.751769929243;6150.846228844225;6185;6186.041163619646;6206.441420272107;6215.160953271341;4813.15769740294;4868.82959096437;4875.049365373789;4876.146674331978;4890.926636100461;4256.318988961813;4276.230278549829;4304.354346847936;4304.978475107667;4323.286657701444;5647.887042259132;5651;5663.716814159276;5682.715864206773;5725.255716756795;6025.457708806229;6130.386961303834;6181.629102253879;6193.194884665055;6209.9068513972325;5349.117395629755;5513.862153446196;5635.267415235993;5640.689762063043;5651.604387692904;5253.474652534716;5266;5270.841874743764;5322.36131664195;5347.171188465794;6237.96881015592;6240.625562466266;6244.219472565929;6254.155688981929;6263.718132684131;5980.581359304807;6171.574139492167;6189.381061893775;6231.532635052298;6243.157173781481;5193.714345710871;6161.921663708923;6192.287886892885;6203.472704820141;6240.843978900564;6102.603330783485;6123.173371594778;6145.723442445012;6162.845928851635;6181.227346581677;6120.207717345286;6252.280987686474;6259.405356491126;6272.655003974825;6294.1188233648245;5824.70876456167;5921.940780592139;5924.348321684525;5958.285005799158;5991.520678345809;5922.289325280994;5930.140129681085;5934.881302373842;5949.6430214187285;5967.671778052249;5815.33123690764;5822.0684690449325;5823.621464604787;5828.154917537018;5834.416558344132;5895.498882595028;5923.555733320104;6048.274207095177;6068.878622427438;6079.544034197364;5829.329627092939;5842.152887831326;5843;5861.179434879035;5877.588568800143 03:19:47 INFO - PROCESS | 1655 | |1;ext.html;510.82887232777017;518.458211169328;553.6484332448895;558.7820749907537;595.0003993291269;34997;37475.81262093686;37484;37497;37632.87101386956;38769.80615096928;39194.412083818694;39252.41121383174;39331.21337573247;39351.80324098383;8407.24334809867;8627.827443451128;9163.35856490046;9360.906390936072;9378.249740020781;8489.74530764078;8567.014793298787;8573.314134869226;10953.17851161162;11273.37996410197;10392.220583456234;10595.947020264926;11238.66284011481;11312.416261723365;11331.943340283287;11221.663350099469;11307.982281594695;11308;11314.830277545885;11317.377544235063;8312.709055183039;8571.357148213909;10804.67585972422;11107.166962477846;11140.220184587082;38484;38788.22423551513;38877.61122388768;38886;38908.22183556313;3155.384699983504;3198.280386912926;3223.9194020149685;3382.1713680148523;3505.526753888218;27874.860625696743;29363.119106426842;29926.70073299261;30346.696533034607;30476;3082.1678146900213;3240.238543942172;3248.5614442050255;3250.626177989514;3334.3998080345527;514794;520155;520169;520327;521741;58254.7087264561;58506.414935850946;58574.41425585689;58586.4141358581;58696.413035869096;61151.69424152851;61174;61455;61553;61553.69223153855;15805.920970395075;15811.604709882346;16175.757363639555;16403.343866245217;16470.011799292082;12616.684582885502;12673.493060277486;12685.23888566689;12697.111202215941;12734.80897786534;14364.569062928129;14485.85514144845;14751.262436878327;15437.845621543865;17956.640867182585;7327.120745510573;7442.851142977219;7492.475526713078;7539.208383119803;7560.281773231534;833.8582440985047;941.091846368258;984.7193549838262;989.3074847606704;1004.9447280399648;26231.639152476368;28750.71249287522;29045.41909161805;29252.561211581844;29336.853315733286;889.1775108025051;1018.1905385218769;1059.1738444013681;1063.404493483636;1086.5436516663021;14253.216073115871;14867.330970106372;14885.627859303604;14933.879959002901;15077.924610376878;653393;691284;695794;696164;697517;43593.5125189984;45483.545164548595;54175.72912135493;54371.45628543664;58210;1321.6960099177052;1336.2850874781946;1365.0444688717937;1367.7196174784344;1368.7673095573975;28858.989935353;29225.70774292315;29607.25981850471;30227.546586800774;30377.848110759303;46601.53398466107;47023.764881175375;47053.529464706284;47142.057158857315;47377;620780.8960955166;623037;624719;625523;625729;25304;25471.36321591975;25735.356616084;25782.484350313265;25813.6127958077;43619.345709813744;43997.56002440063;44571.10857782891;44580.55419445764;45129;30229.09312720622;32070;36750;36907.815460922524;37386.252274954895;36860.26279474449;36877.26245475129;37192.628073718915;37243.44134838034;37278.6272137286;35637.64362356447;36128.819355903055;36331.63668363282;36545.26909461849;36685.26629467449;31472.842635786674;33051.66948330582;33166.66833331732;33281.34061231381;33290.167745806546;23989.880050599637;24240.272791816275;24457.877710611334;24771.380715482257;24804.50390992134 03:19:47 INFO - PROCESS | 1655 | |2;jquery.html;351.03465469957615;376.27754710954963;380.69734561023967;388.8638976358274;407.9204555111754;14260.857391426096;14872.925635371821;15791.921040394796;19704.802951970476;20575.897120514415;18934.242630294808;19308.710369344437;19600.803991960078;20076.895770732604;20223;5569.415211402795;5788.47903688668;5805.100209467518;5808.59339846211;5814.592978491507;4653.8371157009515;5783.074708046714;5793.986052440827;5841.211436456067;5853.8829223415505;5503.889922201573;5787.971060144714;5798.9130163047585;5816.505597024258;5827;5651.9152212716845;5761.337446193699;5761.395053519362;5774.422557744234;5777.5666824987875;4367.410399596078;4815.614750819961;5002.57478114357;5072.974635126838;5079.390473143209;24131.034758609636;24317;24558.631620525706;25383.87308063448;25551.488970220493;2178.6405243134864;2453.779159875602;2469.6295555666616;2735.822171558862;2882.4811533923885;10544.789104217873;14361.928190358982;14521.419143234258;14671;15355.846441535554;1839.7976222615503;1912.3880358285355;1942.2813558983125;1947.5812700269369;1958.343954775152;122594.38702806429;122779;128390;131336;132227.33886330604;11187.832182517268;11547.133964952494;11691.895458817324;11828.467718952668;12158.696032599255;14912.47806326795;14926.477573284883;14927.626809329853;14931.328090235747;14937.402503899722;6714.3957043866285;6777.288384719632;6897.7585784497305;7727.343175830005;7965.163657815961;8314.958425207957;8340;8365.539895305817;8489.83020339602;8504.65981360751;4547.613452856478;4554.134714404253;4557.430321209848;4601.976990115095;4621.30680397943;1776.8845025073588;1809.058043882407;1828.7531183290353;1881.1628825172668;1940.506449212755;742.3096520236232;801.3148757812103;833.629035079396;905.4295793649958;905.9139381758722;18694.439166825017;20728.170873165207;20772.79227207739;20880.686789698168;21080.262190823247;751.4251597527896;886.1050339157497;886.9157430044136;903.1149473515991;933.8272419602396;8391;11023.503942322615;11092.55629774816;11135.888641113646;11143.832842507369;443445;455555;473106;482687.58656206494;483289;116542.83457165319;116722;118661;119216;119362;888.6936203780432;927.6335847340263;938.77000134966;944.8724422203189;976.7167521418709;17939.103044847965;18100.27598896082;18380.54048648741;18419;18485.63028739391;41623.37564936589;52658.473415265355;52870;54150.3754887354;54850.72574637101;66191.33808662044;71872.28127718656;74737;75400;75946.62026690053;7045.330693584101;7267.709291628275;7377.77866664001;7463.925360746323;7646.426518011061;10207.234457415576;12302.75394492123;12832.230066196058;13095.541656041993;13393.330333483093;5487.725613719378;5627.8593035174445;10859.371094335913;10904;11765.470553824927;10991.670249892515;11001.339919604849;11086.889131108908;11358.60244891425;11402.714932126763;11224.775504490028;11270.83093753578;11821.113416493625;11856.762864742484;11869.347185904859;7440.479166458296;7505.399568034435;7689.692412303445;7832;7893.447458677838;10089.747756306151;10974.835377469184;11447.027002704863;11589.710257243636;11634.301941883514 03:19:47 INFO - PROCESS | 1655 | |3;mootools.html;1449.7752848308517;1464.54599074287;1549.256356948664;1600.927958241879;1772.1493683032145;7471.962640186792;7745.651445684944;7981.52110873348;8469.830603387929;8478.067412584618;7616;8031.879521807177;8243.464174828632;8266.049404318504;8386.37102217333;1962.362232274513;2339.8596084234914;2341.718993720747;2343.6718859359703;2349.95300093998;2080.2615071649498;2338.777816107467;2367.9408014799683;2392.808575313971;2398.4123889646976;2102.4638717127186;2131.8294536437124;2132.2110818996994;2134.039682143034;2260.367097212783;2322.140807901079;2325.7674232576605;2330.2310237621546;2331.3355693627236;2343.589871772433;2337.941551461227;2349.553584818879;2368.8578685278767;2377.0135393811547;2389.3668177932864;7998.440109192359;8087.716929907484;8099.919000810034;8116.959415202945;8418.49489030654;5018;5188.662736922088;5192.818251361222;5197.7920883164625;5205.453427390145;2497.325722055035;2788.2750484873877;3351.5307856900054;3351.692839792483;3423.5378223939697;949.4161090929098;969.9127078562963;1002.8345323021692;1006.7332156978407;1009.7728011197495;9329.860052099224;10962.232643714942;11045.889541104567;11065.944670276678;11067.280626759315;4563.977180114078;4574.725516469022;4755.595774359218;4763.285507173951;4769.284607308931;9464.52677366129;9472.763180920394;9486.33595648298;9504.572294246775;9536.094071063239;5203.401608815035;5206.062908676399;5216.921746173811;5218.321618189611;5221.530062294336;4676.994446194085;4692.835750748707;4728.527147285244;4785.234362501983;4809.158397280462;3820.8853734388013;3824.847006119724;3882.8446862125197;3908.726389152732;3922.0783115967724;1092.6612750047511;1245.9160530338663;1259.1752402176405;1270.7458508298378;1276.5276847566274;761.39469122047;882.139913584263;891.4517571693361;901.324006994754;909.736176508805;5544.946460172635;5612.579056570774;5711.400302968212;5794.449527294901;5794.594378393472;927.5408672706918;949.6011675096476;957.8936328540547;982.3074732313702;986.6743974488402;3722.4974628424816;3812.675922546559;3818.866339678098;3910.061585219527;3930.744501607386;9916.950415248022;10161.745956351151;10272.743181420525;10277.794444111076;10302.948485257526;10104.747381315527;10251.487425628839;10256.948715256376;10287.588496460057;10288;7635.465517413728;7816.49192802466;7841.803954901059;7965;7968.920310796933;4799.448063472606;5070.340855688737;5420.376656684374;5715.942840571707;5781.82654520365;8435.493870367798;9341.626334946526;9348.53257337144;9433.151016408556;9475.810483790423;9360.906390936003;9445.811083778424;9460.574274157541;9568.186704129948;9759.12167904892;6984.650767461505;6990.09128813251;7150.494586651026;7344.853102938018;7352.264773522605;7898.565578892986;7926.445148839526;7989.001374828146;8003.439759216799;8024.478408903403;7146.464015198777;7225.674844631899;7272.200057993511;7277.890831637424;7385.113786345673;7022.323344210624;7072.752453664097;7079.469039821931;7084.25615310385;7177.138743350832;6678.398944094861;6821.3178682133375;6824.4199243064895;6827.692753825991;6831.692573834289;4818.404938393947;4859.392575928009;4896.926546101882;4935.580475659609;4991.276264941635;4762.517316445648;4770.2129148691465;4835.564799168092;4993.775280112331;5001.974990125172 03:19:47 INFO - PROCESS | 1655 | |4;prototype.html;249.92377324915898;285.5174754664615;296.3729127092263;299.561142925614;317.55859355495863;4687.8124875005005;4839.927401088982;4965.5034496550325;5188.558972487333;5269.604779641532;4286.464191976003;4575.839845605405;5165.354330708661;5180.45605211453;5205.9219111713355;3606.4590311453294;3974.3243648579664;3978.482797236355;3981.5421226558883;4012.374988129812;3280.6883346082204;3839.1745774658443;3948.644621984021;3966.504186976628;3981.0246489610086;3944.4280579315982;3947.3486874665646;3955.8417663293435;3962.2075584882996;3968.7420317679403;3918.6488107135774;3931.744436611611;3940.310445671996;3942.7042971777087;3957.2085582883537;2899.811512251697;2999.5200767877036;3431.661651955739;3440.6043305019743;3457.7060949819297;4848.22428411456;4979.651424400294;4986.82546110888;5031.547160755513;5047.318611987372;4435.711678740904;4438.489573699034;4443.37792709021;4447.444069491314;4466.441694788152;5356.571474282048;5366.651167674088;5439.864003399946;5443.591730620219;5469.316335458067;3070.8925187618324;3456.9308613827584;3630.6550877666577;3726.366517691999;3734.290484807878;4901.137567724242;5209.947900520984;5654.264945557051;5722.484976352148;5734.541236701054;3539.610642829287;3804.2771873343977;3807.600201978808;3822.522184726909;3841.8496832597;3571.964130402206;3742.7567208131386;3870.53553573573;3879.8254078566533;3883.689304855605;4202.810873510699;4239.964816887911;4302.118065796504;4332.761698106634;4335.1979883721615;4547.454305483363;4769.260764581495;4772.236442169236;5456.945430545644;5483.725813709299;1150.71232191952;1193.785118678646;1225.8161275808523;1246.3394400967502;1248.6628610275175;721.1850608812009;816.6406781016334;817.824167803925;846.204567706354;862.0775769926245;3929.92140157201;4279.358096285582;4453.75504347264;4558.270676691714;4773.71357718538;4300.419443375167;4591.655625828076;4989.983063633809;5198.868463929771;5206.843794686166;4365.930347064926;4430.534793846664;4652.232381657056;4730.924898815729;4754.9286760698615;2653.81423300371;2739.4794988952035;2748.502066373831;2755.456944111304;2761.751442370156;4469.955300447019;4864.95135048645;5075.949240507548;5336.385171266052;5401.891962160735;4615.769211539477;4826.756760803023;5136.229565565195;5228.2680424740565;5344.412114667383;4523.411956445641;5049.394072711225;5315.468453154654;5373.785048598091;5406.216098665672;5123.976029468265;5204.635675502678;5207.27098206259;5215.374155101412;5219.791208351775;4037.5154981401247;4506;4902.63230257725;5161.148410512374;5175.844724658266;5341.41244463101;5368.355797304349;5378.247045413724;5394.433584473573;5395.946040539544;3277.0332751837786;3517.1558825881093;3548.119496666771;3551.7691350062164;3566.5363502744476;4225.936610950776;4971.477994810492;5210.713410762292;5225.451327610546;5272.604554658339;4070.898227544335;4686.859394218179;5106.7701953412925;5129.615278854027;5159.587233021424;5172.482751724948;5178.948210517847;5326.280952071421;5451.946909730564;5468.578169675833;5294.491070045019;5322.467753224647;5323.760430780547;5346.304980352529;5360.812371566976;4903.190973489334;5192.480751924928;5391.466010974836;5463.153211252262;5463.836084917459;4225.450691410097;5083.881546059861;5148.97425512885;5315.335583052119;5326.9733651333045 03:19:47 INFO - PROCESS | 1655 | |5;yui.html;664.3920812456603;716.3767522255638;761.1589193940696;761.961901904905;767.9888726062438;13602.659933501667;14207.857921420782;14289.356978935946;14315.141091534502;15528.990615609977;14387.856121438783;14525.927370363133;14659.780103298432;14682;14686.485972990948;717.2039036669292;819.3527113580279;852.7399143261302;855.2901092093548;857.5497863621617;653.2030922274818;674.470540625609;807.4630370803409;830.5930094253832;835.8161204534993;871.0244526130742;884.4030279561302;887.4542156573693;888.404768804899;888.4580405952364;832.0930186097123;832.6919039955195;851.4848516647401;880.4453194487432;881.2157180109707;500.41451501743046;514.3519165851018;628.3779058731868;648.251269783401;649.6037417175567;13706.862931370659;14185.645358866008;14223.359948802328;14228.573142805732;14237.85762142386;12569.371531423483;12755.936220318932;12863.678408039781;12873.485060597564;12991.090623656348;12011.63965081049;12057.577984770578;12135.453904574228;12160.756784864254;12164.574239901562;4140.91718165638;4176.269152898261;4204.096119334327;4409.647228221735;4456.041950980522;81276.59361703154;82792.58603707002;87640;88002.5599872003;88646;9964.950175249078;9988.850167247498;10185.185185185168;10212.438315892548;10474.319169253975;18921;19114.617707645768;19129.90435047816;19252.22991080341;19526.609467810566;7325.743598974011;7358.264173582599;7361.2638736125955;7507.812304692317;7521.8119547010665;4050.25070361984;4092.4884389451317;4116.191607284268;4205.726627769185;4282.272013757669;2823.68939416664;3167.920801979969;3277.6886195811358;3280.5899262592175;3282.4748040313434;599.1761328173761;603.8490377405649;606.2149516376303;611.6116266171014;622.2408661433044;569.6325869813946;601.4406601860225;611.3031144495279;623.4420193926411;625.5246013030039;7883.172266912006;8570;8601.698940537051;8614.61234244448;8626.353023523261;535.7080391186812;558.6508432229856;566.7308028686424;566.9574781891374;592.8340064781868;4037.6971727119994;4041.131156801243;4115.34154535273;4137.317342638431;4244.596763307481;109395.90604093857;112423;113743;115848.42075789567;120158.19762703567;59489;61722;71639.64180179065;97177.51411242898;105665;51437;66528.00207996981;70725.64637176885;82131.5893420529;82411;9027.232685221566;9164.725058247997;9537.570809313458;9576.449146162708;9702.854457183006;40395.59604404036;52112.739436302574;52138;52373.47626523686;53456.93086138178;70540.64729676319;73720;76281;76387.61806190934;78348;11522.654320370402;11620.477078531318;11758.647240582795;11851.881481185423;12218.6945326364;13569.118007329493;13705.246211458465;14173.716525669222;14197.858021420067;14202.431902723776;12334.876651233373;12429.564965226535;12684.429200685807;12717;13229.933850330686;11969.940150299193;12229;12519.436625351698;12558.183718058297;12775.23348599087;12053.63839084829;12156;12173.69565760863;12475.563355282522;12555.874441255835;9651.372660777308;9776.80446391054;10175.484025177111;10182.287239893136;10284.96593504903;10415.375077495375;10847.837282440612;11037.282576632493;11071.72320691989;11138.33170009802 03:19:47 INFO - PROCESS | 1655 | __end_tp_report 03:19:47 INFO - PROCESS | 1655 | __start_cc_report 03:19:47 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2737 03:19:47 INFO - PROCESS | 1655 | __end_cc_report 03:19:47 INFO - PROCESS | 1655 | __startTimestamp1484133587229__endTimestamp 03:19:47 INFO - PROCESS | 1655 | 03:19:47 INFO - PROCESS | 1655 | ------- Summary: start ------- 03:19:47 INFO - PROCESS | 1655 | Number of tests: 6 03:19:47 INFO - PROCESS | 1655 | 03:19:47 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5643.15 Median:5834.42 stddev:661.79 (11.3%) stddev-sans-first:625.26 03:19:47 INFO - PROCESS | 1655 | Values: 2805.2 3257.4 3274.3 3289.0 3316.7 5522.7 5551.5 5577.2 5589.2 5599.1 5555.4 5612.6 5617.9 5653.0 5695.3 5112.5 5114.7 5117.7 5182.2 5254.3 4442.8 5040.7 5054.2 5111.2 5147.7 5118.4 5122.7 5156.6 5171.1 5183.8 5136.8 5172.8 5193.0 5203.1 5234.0 5091.3 5156.4 5198.4 5207.0 5256.1 4639.8 5437.5 5498.4 5506.4 5548.8 6048.6 6063.4 6084.5 6088.8 6103.2 6202.2 6217.7 6227.6 6252.6 6265.9 5574.3 5620.2 5640.4 5642.8 5752.7 6248.5 6260.2 6266.2 6293.3 6301.4 6044.3 6046.8 6057.3 6060.3 6068.0 6030.5 6056.3 6145.5 6160.7 6204.6 6051.5 6079.1 6118.8 6133.7 6205.8 6150.8 6185.0 6186.0 6206.4 6215.2 4813.2 4868.8 4875.0 4876.1 4890.9 4256.3 4276.2 4304.4 4305.0 4323.3 5647.9 5651.0 5663.7 5682.7 5725.3 6025.5 6130.4 6181.6 6193.2 6209.9 5349.1 5513.9 5635.3 5640.7 5651.6 5253.5 5266.0 5270.8 5322.4 5347.2 6238.0 6240.6 6244.2 6254.2 6263.7 5980.6 6171.6 6189.4 6231.5 6243.2 5193.7 6161.9 6192.3 6203.5 6240.8 6102.6 6123.2 6145.7 6162.8 6181.2 6120.2 6252.3 6259.4 6272.7 6294.1 5824.7 5921.9 5924.3 5958.3 5991.5 5922.3 5930.1 5934.9 5949.6 5967.7 5815.3 5822.1 5823.6 5828.2 5834.4 5895.5 5923.6 6048.3 6068.9 6079.5 5829.3 5842.2 5843.0 5861.2 5877.6 03:19:47 INFO - PROCESS | 1655 | 03:19:47 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:72614.79 Median:36438.45 stddev:164611.48 (451.8%) stddev-sans-first:164984.23 03:19:47 INFO - PROCESS | 1655 | Values: 510.8 518.5 553.6 558.8 595.0 34997.0 37475.8 37484.0 37497.0 37632.9 38769.8 39194.4 39252.4 39331.2 39351.8 8407.2 8627.8 9163.4 9360.9 9378.2 8489.7 8567.0 8573.3 10953.2 11273.4 10392.2 10595.9 11238.7 11312.4 11331.9 11221.7 11308.0 11308.0 11314.8 11317.4 8312.7 8571.4 10804.7 11107.2 11140.2 38484.0 38788.2 38877.6 38886.0 38908.2 3155.4 3198.3 3223.9 3382.2 3505.5 27874.9 29363.1 29926.7 30346.7 30476.0 3082.2 3240.2 3248.6 3250.6 3334.4 514794.0 520155.0 520169.0 520327.0 521741.0 58254.7 58506.4 58574.4 58586.4 58696.4 61151.7 61174.0 61455.0 61553.0 61553.7 15805.9 15811.6 16175.8 16403.3 16470.0 12616.7 12673.5 12685.2 12697.1 12734.8 14364.6 14485.9 14751.3 15437.8 17956.6 7327.1 7442.9 7492.5 7539.2 7560.3 833.9 941.1 984.7 989.3 1004.9 26231.6 28750.7 29045.4 29252.6 29336.9 889.2 1018.2 1059.2 1063.4 1086.5 14253.2 14867.3 14885.6 14933.9 15077.9 653393.0 691284.0 695794.0 696164.0 697517.0 43593.5 45483.5 54175.7 54371.5 58210.0 1321.7 1336.3 1365.0 1367.7 1368.8 28859.0 29225.7 29607.3 30227.5 30377.8 46601.5 47023.8 47053.5 47142.1 47377.0 620780.9 623037.0 624719.0 625523.0 625729.0 25304.0 25471.4 25735.4 25782.5 25813.6 43619.3 43997.6 44571.1 44580.6 45129.0 30229.1 32070.0 36750.0 36907.8 37386.3 36860.3 36877.3 37192.6 37243.4 37278.6 35637.6 36128.8 36331.6 36545.3 36685.3 31472.8 33051.7 33166.7 33281.3 33290.2 23989.9 24240.3 24457.9 24771.4 24804.5 03:19:47 INFO - PROCESS | 1655 | 03:19:47 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:30998.41 Median:2809.15 stddev:79500.68 (2830.1%) stddev-sans-first:79690.39 03:19:47 INFO - PROCESS | 1655 | Values: 351.0 376.3 380.7 388.9 407.9 14260.9 14872.9 15791.9 19704.8 20575.9 18934.2 19308.7 19600.8 20076.9 20223.0 5569.4 5788.5 5805.1 5808.6 5814.6 4653.8 5783.1 5794.0 5841.2 5853.9 5503.9 5788.0 5798.9 5816.5 5827.0 5651.9 5761.3 5761.4 5774.4 5777.6 4367.4 4815.6 5002.6 5073.0 5079.4 24131.0 24317.0 24558.6 25383.9 25551.5 2178.6 2453.8 2469.6 2735.8 2882.5 10544.8 14361.9 14521.4 14671.0 15355.8 1839.8 1912.4 1942.3 1947.6 1958.3 122594.4 122779.0 128390.0 131336.0 132227.3 11187.8 11547.1 11691.9 11828.5 12158.7 14912.5 14926.5 14927.6 14931.3 14937.4 6714.4 6777.3 6897.8 7727.3 7965.2 8315.0 8340.0 8365.5 8489.8 8504.7 4547.6 4554.1 4557.4 4602.0 4621.3 1776.9 1809.1 1828.8 1881.2 1940.5 742.3 801.3 833.6 905.4 905.9 18694.4 20728.2 20772.8 20880.7 21080.3 751.4 886.1 886.9 903.1 933.8 8391.0 11023.5 11092.6 11135.9 11143.8 443445.0 455555.0 473106.0 482687.6 483289.0 116542.8 116722.0 118661.0 119216.0 119362.0 888.7 927.6 938.8 944.9 976.7 17939.1 18100.3 18380.5 18419.0 18485.6 41623.4 52658.5 52870.0 54150.4 54850.7 66191.3 71872.3 74737.0 75400.0 75946.6 7045.3 7267.7 7377.8 7463.9 7646.4 10207.2 12302.8 12832.2 13095.5 13393.3 5487.7 5627.9 10859.4 10904.0 11765.5 10991.7 11001.3 11086.9 11358.6 11402.7 11224.8 11270.8 11821.1 11856.8 11869.3 7440.5 7505.4 7689.7 7832.0 7893.4 10089.7 10974.8 11447.0 11589.7 11634.3 03:19:47 INFO - PROCESS | 1655 | 03:19:47 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5450.04 Median:5195.31 stddev:2997.46 (57.7%) stddev-sans-first:2990.80 03:19:47 INFO - PROCESS | 1655 | Values: 1449.8 1464.5 1549.3 1600.9 1772.1 7472.0 7745.7 7981.5 8469.8 8478.1 7616.0 8031.9 8243.5 8266.0 8386.4 1962.4 2339.9 2341.7 2343.7 2350.0 2080.3 2338.8 2367.9 2392.8 2398.4 2102.5 2131.8 2132.2 2134.0 2260.4 2322.1 2325.8 2330.2 2331.3 2343.6 2337.9 2349.6 2368.9 2377.0 2389.4 7998.4 8087.7 8099.9 8117.0 8418.5 5018.0 5188.7 5192.8 5197.8 5205.5 2497.3 2788.3 3351.5 3351.7 3423.5 949.4 969.9 1002.8 1006.7 1009.8 9329.9 10962.2 11045.9 11065.9 11067.3 4564.0 4574.7 4755.6 4763.3 4769.3 9464.5 9472.8 9486.3 9504.6 9536.1 5203.4 5206.1 5216.9 5218.3 5221.5 4677.0 4692.8 4728.5 4785.2 4809.2 3820.9 3824.8 3882.8 3908.7 3922.1 1092.7 1245.9 1259.2 1270.7 1276.5 761.4 882.1 891.5 901.3 909.7 5544.9 5612.6 5711.4 5794.4 5794.6 927.5 949.6 957.9 982.3 986.7 3722.5 3812.7 3818.9 3910.1 3930.7 9917.0 10161.7 10272.7 10277.8 10302.9 10104.7 10251.5 10256.9 10287.6 10288.0 7635.5 7816.5 7841.8 7965.0 7968.9 4799.4 5070.3 5420.4 5715.9 5781.8 8435.5 9341.6 9348.5 9433.2 9475.8 9360.9 9445.8 9460.6 9568.2 9759.1 6984.7 6990.1 7150.5 7344.9 7352.3 7898.6 7926.4 7989.0 8003.4 8024.5 7146.5 7225.7 7272.2 7277.9 7385.1 7022.3 7072.8 7079.5 7084.3 7177.1 6678.4 6821.3 6824.4 6827.7 6831.7 4818.4 4859.4 4896.9 4935.6 4991.3 4762.5 4770.2 4835.6 4993.8 5002.0 03:19:47 INFO - PROCESS | 1655 | 03:19:47 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4251.22 Median:4687.34 stddev:1280.68 (27.3%) stddev-sans-first:1248.56 03:19:47 INFO - PROCESS | 1655 | Values: 249.9 285.5 296.4 299.6 317.6 4687.8 4839.9 4965.5 5188.6 5269.6 4286.5 4575.8 5165.4 5180.5 5205.9 3606.5 3974.3 3978.5 3981.5 4012.4 3280.7 3839.2 3948.6 3966.5 3981.0 3944.4 3947.3 3955.8 3962.2 3968.7 3918.6 3931.7 3940.3 3942.7 3957.2 2899.8 2999.5 3431.7 3440.6 3457.7 4848.2 4979.7 4986.8 5031.5 5047.3 4435.7 4438.5 4443.4 4447.4 4466.4 5356.6 5366.7 5439.9 5443.6 5469.3 3070.9 3456.9 3630.7 3726.4 3734.3 4901.1 5209.9 5654.3 5722.5 5734.5 3539.6 3804.3 3807.6 3822.5 3841.8 3572.0 3742.8 3870.5 3879.8 3883.7 4202.8 4240.0 4302.1 4332.8 4335.2 4547.5 4769.3 4772.2 5456.9 5483.7 1150.7 1193.8 1225.8 1246.3 1248.7 721.2 816.6 817.8 846.2 862.1 3929.9 4279.4 4453.8 4558.3 4773.7 4300.4 4591.7 4990.0 5198.9 5206.8 4365.9 4430.5 4652.2 4730.9 4754.9 2653.8 2739.5 2748.5 2755.5 2761.8 4470.0 4865.0 5075.9 5336.4 5401.9 4615.8 4826.8 5136.2 5228.3 5344.4 4523.4 5049.4 5315.5 5373.8 5406.2 5124.0 5204.6 5207.3 5215.4 5219.8 4037.5 4506.0 4902.6 5161.1 5175.8 5341.4 5368.4 5378.2 5394.4 5395.9 3277.0 3517.2 3548.1 3551.8 3566.5 4225.9 4971.5 5210.7 5225.5 5272.6 4070.9 4686.9 5106.8 5129.6 5159.6 5172.5 5178.9 5326.3 5451.9 5468.6 5294.5 5322.5 5323.8 5346.3 5360.8 4903.2 5192.5 5391.5 5463.2 5463.8 4225.5 5083.9 5149.0 5315.3 5327.0 03:19:47 INFO - PROCESS | 1655 | 03:19:47 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:19548.47 Median:4345.96 stddev:28603.39 (658.2%) stddev-sans-first:28648.48 03:19:47 INFO - PROCESS | 1655 | Values: 664.4 716.4 761.2 762.0 768.0 13602.7 14207.9 14289.4 14315.1 15529.0 14387.9 14525.9 14659.8 14682.0 14686.5 717.2 819.4 852.7 855.3 857.5 653.2 674.5 807.5 830.6 835.8 871.0 884.4 887.5 888.4 888.5 832.1 832.7 851.5 880.4 881.2 500.4 514.4 628.4 648.3 649.6 13706.9 14185.6 14223.4 14228.6 14237.9 12569.4 12755.9 12863.7 12873.5 12991.1 12011.6 12057.6 12135.5 12160.8 12164.6 4140.9 4176.3 4204.1 4409.6 4456.0 81276.6 82792.6 87640.0 88002.6 88646.0 9965.0 9988.9 10185.2 10212.4 10474.3 18921.0 19114.6 19129.9 19252.2 19526.6 7325.7 7358.3 7361.3 7507.8 7521.8 4050.3 4092.5 4116.2 4205.7 4282.3 2823.7 3167.9 3277.7 3280.6 3282.5 599.2 603.8 606.2 611.6 622.2 569.6 601.4 611.3 623.4 625.5 7883.2 8570.0 8601.7 8614.6 8626.4 535.7 558.7 566.7 567.0 592.8 4037.7 4041.1 4115.3 4137.3 4244.6 109395.9 112423.0 113743.0 115848.4 120158.2 59489.0 61722.0 71639.6 97177.5 105665.0 51437.0 66528.0 70725.6 82131.6 82411.0 9027.2 9164.7 9537.6 9576.4 9702.9 40395.6 52112.7 52138.0 52373.5 53456.9 70540.6 73720.0 76281.0 76387.6 78348.0 11522.7 11620.5 11758.6 11851.9 12218.7 13569.1 13705.2 14173.7 14197.9 14202.4 12334.9 12429.6 12684.4 12717.0 13229.9 11969.9 12229.0 12519.4 12558.2 12775.2 12053.6 12156.0 12173.7 12475.6 12555.9 9651.4 9776.8 10175.5 10182.3 10285.0 10415.4 10847.8 11037.3 11071.7 11138.3 03:19:47 INFO - PROCESS | 1655 | -------- Summary: end -------- 03:19:47 INFO - PROCESS | 1655 | 03:19:47 INFO - TEST-INFO | 1655: exit 0 03:19:47 INFO - TEST-OK | dromaeo_css | took 1120061ms 03:19:47 INFO - TEST-START | kraken 03:19:47 INFO - operating with platform_type : mac_ 03:19:47 INFO - Initialising browser for kraken test... 03:19:47 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpGHi4NA/profile http://localhost:49235/getInfo.html) 03:19:50 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 03:19:50 INFO - PROCESS | 1661 | colorDepth:24 03:19:50 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 03:19:50 INFO - PROCESS | 1661 | __metrics 03:19:50 INFO - PROCESS | 1661 | 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" 03:19:50 INFO - PROCESS | 1661 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:19:51 INFO - TEST-INFO | 1661: exit 0 03:19:51 INFO - Browser initialized. 03:19:51 INFO - Running cycle 1/1 for kraken test... 03:19:51 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpGHi4NA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:20:03 INFO - PROCESS | 1663 | RSS: Main: 222437376 03:20:03 INFO - PROCESS | 1663 | 03:20:06 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 03:20:07 INFO - PROCESS | 1663 | RSS: Main: 272482304 03:20:07 INFO - PROCESS | 1663 | 03:20:09 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 03:20:10 INFO - PROCESS | 1663 | RSS: Main: 283582464 03:20:10 INFO - PROCESS | 1663 | 03:20:13 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 03:20:14 INFO - PROCESS | 1663 | RSS: Main: 380776448 03:20:14 INFO - PROCESS | 1663 | 03:20:16 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 03:20:16 INFO - PROCESS | 1663 | RSS: Main: 282558464 03:20:16 INFO - PROCESS | 1663 | 03:20:18 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:20:19 INFO - PROCESS | 1663 | RSS: Main: 284999680 03:20:19 INFO - PROCESS | 1663 | 03:20:22 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 03:20:22 INFO - PROCESS | 1663 | RSS: Main: 326123520 03:20:22 INFO - PROCESS | 1663 | 03:20:25 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 03:20:26 INFO - PROCESS | 1663 | RSS: Main: 325947392 03:20:26 INFO - PROCESS | 1663 | 03:20:29 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 03:20:30 INFO - PROCESS | 1663 | RSS: Main: 324194304 03:20:30 INFO - PROCESS | 1663 | 03:20:31 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:20:32 INFO - PROCESS | 1663 | RSS: Main: 299245568 03:20:32 INFO - PROCESS | 1663 | 03:20:34 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:20:34 INFO - PROCESS | 1663 | RSS: Main: 300797952 03:20:34 INFO - PROCESS | 1663 | 03:20:36 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:20:37 INFO - PROCESS | 1663 | RSS: Main: 304029696 03:20:37 INFO - PROCESS | 1663 | 03:20:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:20:41 INFO - PROCESS | 1663 | RSS: Main: 320843776 03:20:41 INFO - PROCESS | 1663 | 03:20:44 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:20:44 INFO - PROCESS | 1663 | RSS: Main: 314290176 03:20:44 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 03:20:46 INFO - PROCESS | 1663 | RSS: Main: 358092800 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | __start_tp_report 03:20:46 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 03:20:46 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 03:20:46 INFO - PROCESS | 1663 | |i|pagename|runs| 03:20:46 INFO - PROCESS | 1663 | |0;ai-astar;114;114;115;113;112;115;114;112;114;112 03:20:46 INFO - PROCESS | 1663 | |1;audio-beat-detection;143;107;107;168;133;138;110;112;140;110 03:20:46 INFO - PROCESS | 1663 | |2;audio-dft;184;185;187;186;184;181;185;183;184;186 03:20:46 INFO - PROCESS | 1663 | |3;audio-fft;81;62;58;89;61;60;58;57;80;57 03:20:46 INFO - PROCESS | 1663 | |4;audio-oscillator;88;70;95;71;68;69;88;67;69;92 03:20:46 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;87;84;87;85;86;84;88;84;88;84 03:20:46 INFO - PROCESS | 1663 | |6;imaging-darkroom;95;96;100;96;96;143;93;152;160;150 03:20:46 INFO - PROCESS | 1663 | |7;imaging-desaturate;115;120;97;80;81;80;84;84;87;129 03:20:46 INFO - PROCESS | 1663 | |8;json-parse-financial;57;55;55;56;54;58;58;56;56;56 03:20:46 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;68;74;73;66;66;68;70;69;69;72 03:20:46 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;82;83;84;83;88;83;83;82;84;83 03:20:46 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;142;159;154;147;153;160;142;140;190;139 03:20:46 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;165;162;162;167;165;163;162;161;164;162 03:20:46 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;67;65;68;67;63;64;69;67;81;82 03:20:46 INFO - PROCESS | 1663 | __end_tp_report 03:20:46 INFO - PROCESS | 1663 | __start_cc_report 03:20:46 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1985 03:20:46 INFO - PROCESS | 1663 | __end_cc_report 03:20:46 INFO - PROCESS | 1663 | __startTimestamp1484133646667__endTimestamp 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | ------- Summary: start ------- 03:20:46 INFO - PROCESS | 1663 | Number of tests: 14 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:113.50 Median:114.00 stddev:1.18 (1.0%) stddev-sans-first:1.24 03:20:46 INFO - PROCESS | 1663 | Values: 114.0 114.0 115.0 113.0 112.0 115.0 114.0 112.0 114.0 112.0 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:126.80 Median:135.50 stddev:20.72 (15.3%) stddev-sans-first:21.14 03:20:46 INFO - PROCESS | 1663 | Values: 143.0 107.0 107.0 168.0 133.0 138.0 110.0 112.0 140.0 110.0 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.00 stddev:1.72 (0.9%) stddev-sans-first:1.81 03:20:46 INFO - PROCESS | 1663 | Values: 184.0 185.0 187.0 186.0 184.0 181.0 185.0 183.0 184.0 186.0 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:66.30 Median:61.50 stddev:12.09 (19.7%) stddev-sans-first:11.60 03:20:46 INFO - PROCESS | 1663 | Values: 81.0 62.0 58.0 89.0 61.0 60.0 58.0 57.0 80.0 57.0 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:79.50 stddev:11.45 (14.4%) stddev-sans-first:11.52 03:20:46 INFO - PROCESS | 1663 | Values: 88.0 70.0 95.0 71.0 68.0 69.0 88.0 67.0 69.0 92.0 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.50 stddev:1.70 (2.0%) stddev-sans-first:1.74 03:20:46 INFO - PROCESS | 1663 | Values: 87.0 84.0 87.0 85.0 86.0 84.0 88.0 84.0 88.0 84.0 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:118.10 Median:94.00 stddev:28.87 (30.7%) stddev-sans-first:29.38 03:20:46 INFO - PROCESS | 1663 | Values: 95.0 96.0 100.0 96.0 96.0 143.0 93.0 152.0 160.0 150.0 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:82.50 stddev:18.66 (22.6%) stddev-sans-first:18.43 03:20:46 INFO - PROCESS | 1663 | Values: 115.0 120.0 97.0 80.0 81.0 80.0 84.0 84.0 87.0 129.0 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:56.00 stddev:1.29 (2.3%) stddev-sans-first:1.32 03:20:46 INFO - PROCESS | 1663 | Values: 57.0 55.0 55.0 56.0 54.0 58.0 58.0 56.0 56.0 56.0 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:69.50 stddev:2.76 (4.0%) stddev-sans-first:2.87 03:20:46 INFO - PROCESS | 1663 | Values: 68.0 74.0 73.0 66.0 66.0 68.0 70.0 69.0 69.0 72.0 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:83.00 stddev:1.72 (2.1%) stddev-sans-first:1.73 03:20:46 INFO - PROCESS | 1663 | Values: 82.0 83.0 84.0 83.0 88.0 83.0 83.0 82.0 84.0 83.0 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:152.60 Median:153.50 stddev:15.26 (9.9%) stddev-sans-first:15.70 03:20:46 INFO - PROCESS | 1663 | Values: 142.0 159.0 154.0 147.0 153.0 160.0 142.0 140.0 190.0 139.0 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.30 Median:163.50 stddev:1.89 (1.2%) stddev-sans-first:1.90 03:20:46 INFO - PROCESS | 1663 | Values: 165.0 162.0 162.0 167.0 165.0 163.0 162.0 161.0 164.0 162.0 03:20:46 INFO - PROCESS | 1663 | 03:20:46 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:67.50 stddev:6.68 (9.9%) stddev-sans-first:7.04 03:20:46 INFO - PROCESS | 1663 | Values: 67.0 65.0 68.0 67.0 63.0 64.0 69.0 67.0 81.0 82.0 03:20:46 INFO - PROCESS | 1663 | -------- Summary: end -------- 03:20:46 INFO - PROCESS | 1663 | 03:20:47 INFO - TEST-INFO | 1663: exit 0 03:20:47 INFO - TEST-OK | kraken | took 59877ms 03:20:47 INFO - SUITE-END | took 1179s 03:20:47 INFO - Completed test suite (00:19:39) 03:20:47 INFO - javascript benchmark 03:20:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2805.2145399288192, 3257.4299497587917, 3274.2796584751345, 3288.9968559589315, 3316.6683331666836, 5522.668639881605, 5551.500364967163, 5577.191307260445, 5589.245451863997, 5599.132134519145, 5555.416681248461, 5612.550995920326, 5617.887642247153, 5653.0, 5695.345035320939, 5112.488751124895, 5114.693118412889, 5117.692938423706, 5182.248573956774, 5254.290670759437, 4442.777861106948, 5040.747962601874, 5054.21659642756, 5111.207762796773, 5147.7426128693605, 5118.360204974379, 5122.667026643257, 5156.561692256163, 5171.0692075426405, 5183.792648294064, 5136.768845401966, 5172.793088276464, 5193.039287731782, 5203.115470370046, 5234.0, 5091.338126043591, 5156.381234251876, 5198.376194856603, 5207.010667973111, 5256.106461901448, 4639.791209395586, 5437.456254374571, 5498.367687715924, 5506.366767821672, 5548.805791797308, 6048.606840555394, 6063.423974722395, 6084.452399284086, 6088.786892458743, 6103.23709536308, 6202.19371481709, 6217.720202590895, 6227.626342419469, 6252.562320637558, 6265.906011409833, 5574.331080270394, 5620.241267428844, 5640.379558248589, 5642.802501912413, 5752.6548407096125, 6248.5313601480075, 6260.248770147611, 6266.216722909637, 6293.2762732285455, 6301.40136687014, 6044.274687037496, 6046.758129674852, 6057.3336932937345, 6060.33336333003, 6067.969660151671, 6030.517558595302, 6056.3338032816355, 6145.480353055315, 6160.661163636043, 6204.5967012144065, 6051.546134039877, 6079.148919151234, 6118.800145424834, 6133.65167052099, 6205.751769929243, 6150.846228844225, 6185.0, 6186.041163619646, 6206.441420272107, 6215.160953271341, 4813.15769740294, 4868.82959096437, 4875.049365373789, 4876.146674331978, 4890.926636100461, 4256.318988961813, 4276.230278549829, 4304.354346847936, 4304.978475107667, 4323.286657701444, 5647.887042259132, 5651.0, 5663.716814159276, 5682.715864206773, 5725.255716756795, 6025.457708806229, 6130.386961303834, 6181.629102253879, 6193.194884665055, 6209.9068513972325, 5349.117395629755, 5513.862153446196, 5635.267415235993, 5640.689762063043, 5651.604387692904, 5253.474652534716, 5266.0, 5270.841874743764, 5322.36131664195, 5347.171188465794, 6237.96881015592, 6240.625562466266, 6244.219472565929, 6254.155688981929, 6263.718132684131, 5980.581359304807, 6171.574139492167, 6189.381061893775, 6231.532635052298, 6243.157173781481, 5193.714345710871, 6161.921663708923, 6192.287886892885, 6203.472704820141, 6240.843978900564, 6102.603330783485, 6123.173371594778, 6145.723442445012, 6162.845928851635, 6181.227346581677, 6120.207717345286, 6252.280987686474, 6259.405356491126, 6272.655003974825, 6294.1188233648245, 5824.70876456167, 5921.940780592139, 5924.348321684525, 5958.285005799158, 5991.520678345809, 5922.289325280994, 5930.140129681085, 5934.881302373842, 5949.6430214187285, 5967.671778052249, 5815.33123690764, 5822.0684690449325, 5823.621464604787, 5828.154917537018, 5834.416558344132, 5895.498882595028, 5923.555733320104, 6048.274207095177, 6068.878622427438, 6079.544034197364, 5829.329627092939, 5842.152887831326, 5843.0, 5861.179434879035, 5877.588568800143], "value": 5598.111662753462, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.82887232777017, 518.458211169328, 553.6484332448895, 558.7820749907537, 595.0003993291269, 34997.0, 37475.81262093686, 37484.0, 37497.0, 37632.87101386956, 38769.80615096928, 39194.412083818694, 39252.41121383174, 39331.21337573247, 39351.80324098383, 8407.24334809867, 8627.827443451128, 9163.35856490046, 9360.906390936072, 9378.249740020781, 8489.74530764078, 8567.014793298787, 8573.314134869226, 10953.17851161162, 11273.37996410197, 10392.220583456234, 10595.947020264926, 11238.66284011481, 11312.416261723365, 11331.943340283287, 11221.663350099469, 11307.982281594695, 11308.0, 11314.830277545885, 11317.377544235063, 8312.709055183039, 8571.357148213909, 10804.67585972422, 11107.166962477846, 11140.220184587082, 38484.0, 38788.22423551513, 38877.61122388768, 38886.0, 38908.22183556313, 3155.384699983504, 3198.280386912926, 3223.9194020149685, 3382.1713680148523, 3505.526753888218, 27874.860625696743, 29363.119106426842, 29926.70073299261, 30346.696533034607, 30476.0, 3082.1678146900213, 3240.238543942172, 3248.5614442050255, 3250.626177989514, 3334.3998080345527, 514794.0, 520155.0, 520169.0, 520327.0, 521741.0, 58254.7087264561, 58506.414935850946, 58574.41425585689, 58586.4141358581, 58696.413035869096, 61151.69424152851, 61174.0, 61455.0, 61553.0, 61553.69223153855, 15805.920970395075, 15811.604709882346, 16175.757363639555, 16403.343866245217, 16470.011799292082, 12616.684582885502, 12673.493060277486, 12685.23888566689, 12697.111202215941, 12734.80897786534, 14364.569062928129, 14485.85514144845, 14751.262436878327, 15437.845621543865, 17956.640867182585, 7327.120745510573, 7442.851142977219, 7492.475526713078, 7539.208383119803, 7560.281773231534, 833.8582440985047, 941.091846368258, 984.7193549838262, 989.3074847606704, 1004.9447280399648, 26231.639152476368, 28750.71249287522, 29045.41909161805, 29252.561211581844, 29336.853315733286, 889.1775108025051, 1018.1905385218769, 1059.1738444013681, 1063.404493483636, 1086.5436516663021, 14253.216073115871, 14867.330970106372, 14885.627859303604, 14933.879959002901, 15077.924610376878, 653393.0, 691284.0, 695794.0, 696164.0, 697517.0, 43593.5125189984, 45483.545164548595, 54175.72912135493, 54371.45628543664, 58210.0, 1321.6960099177052, 1336.2850874781946, 1365.0444688717937, 1367.7196174784344, 1368.7673095573975, 28858.989935353, 29225.70774292315, 29607.25981850471, 30227.546586800774, 30377.848110759303, 46601.53398466107, 47023.764881175375, 47053.529464706284, 47142.057158857315, 47377.0, 620780.8960955166, 623037.0, 624719.0, 625523.0, 625729.0, 25304.0, 25471.36321591975, 25735.356616084, 25782.484350313265, 25813.6127958077, 43619.345709813744, 43997.56002440063, 44571.10857782891, 44580.55419445764, 45129.0, 30229.09312720622, 32070.0, 36750.0, 36907.815460922524, 37386.252274954895, 36860.26279474449, 36877.26245475129, 37192.628073718915, 37243.44134838034, 37278.6272137286, 35637.64362356447, 36128.819355903055, 36331.63668363282, 36545.26909461849, 36685.26629467449, 31472.842635786674, 33051.66948330582, 33166.66833331732, 33281.34061231381, 33290.167745806546, 23989.880050599637, 24240.272791816275, 24457.877710611334, 24771.380715482257, 24804.50390992134], "value": 19596.359183942473, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.03465469957615, 376.27754710954963, 380.69734561023967, 388.8638976358274, 407.9204555111754, 14260.857391426096, 14872.925635371821, 15791.921040394796, 19704.802951970476, 20575.897120514415, 18934.242630294808, 19308.710369344437, 19600.803991960078, 20076.895770732604, 20223.0, 5569.415211402795, 5788.47903688668, 5805.100209467518, 5808.59339846211, 5814.592978491507, 4653.8371157009515, 5783.074708046714, 5793.986052440827, 5841.211436456067, 5853.8829223415505, 5503.889922201573, 5787.971060144714, 5798.9130163047585, 5816.505597024258, 5827.0, 5651.9152212716845, 5761.337446193699, 5761.395053519362, 5774.422557744234, 5777.5666824987875, 4367.410399596078, 4815.614750819961, 5002.57478114357, 5072.974635126838, 5079.390473143209, 24131.034758609636, 24317.0, 24558.631620525706, 25383.87308063448, 25551.488970220493, 2178.6405243134864, 2453.779159875602, 2469.6295555666616, 2735.822171558862, 2882.4811533923885, 10544.789104217873, 14361.928190358982, 14521.419143234258, 14671.0, 15355.846441535554, 1839.7976222615503, 1912.3880358285355, 1942.2813558983125, 1947.5812700269369, 1958.343954775152, 122594.38702806429, 122779.0, 128390.0, 131336.0, 132227.33886330604, 11187.832182517268, 11547.133964952494, 11691.895458817324, 11828.467718952668, 12158.696032599255, 14912.47806326795, 14926.477573284883, 14927.626809329853, 14931.328090235747, 14937.402503899722, 6714.3957043866285, 6777.288384719632, 6897.7585784497305, 7727.343175830005, 7965.163657815961, 8314.958425207957, 8340.0, 8365.539895305817, 8489.83020339602, 8504.65981360751, 4547.613452856478, 4554.134714404253, 4557.430321209848, 4601.976990115095, 4621.30680397943, 1776.8845025073588, 1809.058043882407, 1828.7531183290353, 1881.1628825172668, 1940.506449212755, 742.3096520236232, 801.3148757812103, 833.629035079396, 905.4295793649958, 905.9139381758722, 18694.439166825017, 20728.170873165207, 20772.79227207739, 20880.686789698168, 21080.262190823247, 751.4251597527896, 886.1050339157497, 886.9157430044136, 903.1149473515991, 933.8272419602396, 8391.0, 11023.503942322615, 11092.55629774816, 11135.888641113646, 11143.832842507369, 443445.0, 455555.0, 473106.0, 482687.58656206494, 483289.0, 116542.83457165319, 116722.0, 118661.0, 119216.0, 119362.0, 888.6936203780432, 927.6335847340263, 938.77000134966, 944.8724422203189, 976.7167521418709, 17939.103044847965, 18100.27598896082, 18380.54048648741, 18419.0, 18485.63028739391, 41623.37564936589, 52658.473415265355, 52870.0, 54150.3754887354, 54850.72574637101, 66191.33808662044, 71872.28127718656, 74737.0, 75400.0, 75946.62026690053, 7045.330693584101, 7267.709291628275, 7377.77866664001, 7463.925360746323, 7646.426518011061, 10207.234457415576, 12302.75394492123, 12832.230066196058, 13095.541656041993, 13393.330333483093, 5487.725613719378, 5627.8593035174445, 10859.371094335913, 10904.0, 11765.470553824927, 10991.670249892515, 11001.339919604849, 11086.889131108908, 11358.60244891425, 11402.714932126763, 11224.775504490028, 11270.83093753578, 11821.113416493625, 11856.762864742484, 11869.347185904859, 7440.479166458296, 7505.399568034435, 7689.692412303445, 7832.0, 7893.447458677838, 10089.747756306151, 10974.835377469184, 11447.027002704863, 11589.710257243636, 11634.301941883514], "value": 9262.867063109734, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1449.7752848308517, 1464.54599074287, 1549.256356948664, 1600.927958241879, 1772.1493683032145, 7471.962640186792, 7745.651445684944, 7981.52110873348, 8469.830603387929, 8478.067412584618, 7616.0, 8031.879521807177, 8243.464174828632, 8266.049404318504, 8386.37102217333, 1962.362232274513, 2339.8596084234914, 2341.718993720747, 2343.6718859359703, 2349.95300093998, 2080.2615071649498, 2338.777816107467, 2367.9408014799683, 2392.808575313971, 2398.4123889646976, 2102.4638717127186, 2131.8294536437124, 2132.2110818996994, 2134.039682143034, 2260.367097212783, 2322.140807901079, 2325.7674232576605, 2330.2310237621546, 2331.3355693627236, 2343.589871772433, 2337.941551461227, 2349.553584818879, 2368.8578685278767, 2377.0135393811547, 2389.3668177932864, 7998.440109192359, 8087.716929907484, 8099.919000810034, 8116.959415202945, 8418.49489030654, 5018.0, 5188.662736922088, 5192.818251361222, 5197.7920883164625, 5205.453427390145, 2497.325722055035, 2788.2750484873877, 3351.5307856900054, 3351.692839792483, 3423.5378223939697, 949.4161090929098, 969.9127078562963, 1002.8345323021692, 1006.7332156978407, 1009.7728011197495, 9329.860052099224, 10962.232643714942, 11045.889541104567, 11065.944670276678, 11067.280626759315, 4563.977180114078, 4574.725516469022, 4755.595774359218, 4763.285507173951, 4769.284607308931, 9464.52677366129, 9472.763180920394, 9486.33595648298, 9504.572294246775, 9536.094071063239, 5203.401608815035, 5206.062908676399, 5216.921746173811, 5218.321618189611, 5221.530062294336, 4676.994446194085, 4692.835750748707, 4728.527147285244, 4785.234362501983, 4809.158397280462, 3820.8853734388013, 3824.847006119724, 3882.8446862125197, 3908.726389152732, 3922.0783115967724, 1092.6612750047511, 1245.9160530338663, 1259.1752402176405, 1270.7458508298378, 1276.5276847566274, 761.39469122047, 882.139913584263, 891.4517571693361, 901.324006994754, 909.736176508805, 5544.946460172635, 5612.579056570774, 5711.400302968212, 5794.449527294901, 5794.594378393472, 927.5408672706918, 949.6011675096476, 957.8936328540547, 982.3074732313702, 986.6743974488402, 3722.4974628424816, 3812.675922546559, 3818.866339678098, 3910.061585219527, 3930.744501607386, 9916.950415248022, 10161.745956351151, 10272.743181420525, 10277.794444111076, 10302.948485257526, 10104.747381315527, 10251.487425628839, 10256.948715256376, 10287.588496460057, 10288.0, 7635.465517413728, 7816.49192802466, 7841.803954901059, 7965.0, 7968.920310796933, 4799.448063472606, 5070.340855688737, 5420.376656684374, 5715.942840571707, 5781.82654520365, 8435.493870367798, 9341.626334946526, 9348.53257337144, 9433.151016408556, 9475.810483790423, 9360.906390936003, 9445.811083778424, 9460.574274157541, 9568.186704129948, 9759.12167904892, 6984.650767461505, 6990.09128813251, 7150.494586651026, 7344.853102938018, 7352.264773522605, 7898.565578892986, 7926.445148839526, 7989.001374828146, 8003.439759216799, 8024.478408903403, 7146.464015198777, 7225.674844631899, 7272.200057993511, 7277.890831637424, 7385.113786345673, 7022.323344210624, 7072.752453664097, 7079.469039821931, 7084.25615310385, 7177.138743350832, 6678.398944094861, 6821.3178682133375, 6824.4199243064895, 6827.692753825991, 6831.692573834289, 4818.404938393947, 4859.392575928009, 4896.926546101882, 4935.580475659609, 4991.276264941635, 4762.517316445648, 4770.2129148691465, 4835.564799168092, 4993.775280112331, 5001.974990125172], "value": 4405.285191894377, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [249.92377324915898, 285.5174754664615, 296.3729127092263, 299.561142925614, 317.55859355495863, 4687.8124875005005, 4839.927401088982, 4965.5034496550325, 5188.558972487333, 5269.604779641532, 4286.464191976003, 4575.839845605405, 5165.354330708661, 5180.45605211453, 5205.9219111713355, 3606.4590311453294, 3974.3243648579664, 3978.482797236355, 3981.5421226558883, 4012.374988129812, 3280.6883346082204, 3839.1745774658443, 3948.644621984021, 3966.504186976628, 3981.0246489610086, 3944.4280579315982, 3947.3486874665646, 3955.8417663293435, 3962.2075584882996, 3968.7420317679403, 3918.6488107135774, 3931.744436611611, 3940.310445671996, 3942.7042971777087, 3957.2085582883537, 2899.811512251697, 2999.5200767877036, 3431.661651955739, 3440.6043305019743, 3457.7060949819297, 4848.22428411456, 4979.651424400294, 4986.82546110888, 5031.547160755513, 5047.318611987372, 4435.711678740904, 4438.489573699034, 4443.37792709021, 4447.444069491314, 4466.441694788152, 5356.571474282048, 5366.651167674088, 5439.864003399946, 5443.591730620219, 5469.316335458067, 3070.8925187618324, 3456.9308613827584, 3630.6550877666577, 3726.366517691999, 3734.290484807878, 4901.137567724242, 5209.947900520984, 5654.264945557051, 5722.484976352148, 5734.541236701054, 3539.610642829287, 3804.2771873343977, 3807.600201978808, 3822.522184726909, 3841.8496832597, 3571.964130402206, 3742.7567208131386, 3870.53553573573, 3879.8254078566533, 3883.689304855605, 4202.810873510699, 4239.964816887911, 4302.118065796504, 4332.761698106634, 4335.1979883721615, 4547.454305483363, 4769.260764581495, 4772.236442169236, 5456.945430545644, 5483.725813709299, 1150.71232191952, 1193.785118678646, 1225.8161275808523, 1246.3394400967502, 1248.6628610275175, 721.1850608812009, 816.6406781016334, 817.824167803925, 846.204567706354, 862.0775769926245, 3929.92140157201, 4279.358096285582, 4453.75504347264, 4558.270676691714, 4773.71357718538, 4300.419443375167, 4591.655625828076, 4989.983063633809, 5198.868463929771, 5206.843794686166, 4365.930347064926, 4430.534793846664, 4652.232381657056, 4730.924898815729, 4754.9286760698615, 2653.81423300371, 2739.4794988952035, 2748.502066373831, 2755.456944111304, 2761.751442370156, 4469.955300447019, 4864.95135048645, 5075.949240507548, 5336.385171266052, 5401.891962160735, 4615.769211539477, 4826.756760803023, 5136.229565565195, 5228.2680424740565, 5344.412114667383, 4523.411956445641, 5049.394072711225, 5315.468453154654, 5373.785048598091, 5406.216098665672, 5123.976029468265, 5204.635675502678, 5207.27098206259, 5215.374155101412, 5219.791208351775, 4037.5154981401247, 4506.0, 4902.63230257725, 5161.148410512374, 5175.844724658266, 5341.41244463101, 5368.355797304349, 5378.247045413724, 5394.433584473573, 5395.946040539544, 3277.0332751837786, 3517.1558825881093, 3548.119496666771, 3551.7691350062164, 3566.5363502744476, 4225.936610950776, 4971.477994810492, 5210.713410762292, 5225.451327610546, 5272.604554658339, 4070.898227544335, 4686.859394218179, 5106.7701953412925, 5129.615278854027, 5159.587233021424, 5172.482751724948, 5178.948210517847, 5326.280952071421, 5451.946909730564, 5468.578169675833, 5294.491070045019, 5322.467753224647, 5323.760430780547, 5346.304980352529, 5360.812371566976, 4903.190973489334, 5192.480751924928, 5391.466010974836, 5463.153211252262, 5463.836084917459, 4225.450691410097, 5083.881546059861, 5148.97425512885, 5315.335583052119, 5326.9733651333045], "value": 3839.217060921, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.3920812456603, 716.3767522255638, 761.1589193940696, 761.961901904905, 767.9888726062438, 13602.659933501667, 14207.857921420782, 14289.356978935946, 14315.141091534502, 15528.990615609977, 14387.856121438783, 14525.927370363133, 14659.780103298432, 14682.0, 14686.485972990948, 717.2039036669292, 819.3527113580279, 852.7399143261302, 855.2901092093548, 857.5497863621617, 653.2030922274818, 674.470540625609, 807.4630370803409, 830.5930094253832, 835.8161204534993, 871.0244526130742, 884.4030279561302, 887.4542156573693, 888.404768804899, 888.4580405952364, 832.0930186097123, 832.6919039955195, 851.4848516647401, 880.4453194487432, 881.2157180109707, 500.41451501743046, 514.3519165851018, 628.3779058731868, 648.251269783401, 649.6037417175567, 13706.862931370659, 14185.645358866008, 14223.359948802328, 14228.573142805732, 14237.85762142386, 12569.371531423483, 12755.936220318932, 12863.678408039781, 12873.485060597564, 12991.090623656348, 12011.63965081049, 12057.577984770578, 12135.453904574228, 12160.756784864254, 12164.574239901562, 4140.91718165638, 4176.269152898261, 4204.096119334327, 4409.647228221735, 4456.041950980522, 81276.59361703154, 82792.58603707002, 87640.0, 88002.5599872003, 88646.0, 9964.950175249078, 9988.850167247498, 10185.185185185168, 10212.438315892548, 10474.319169253975, 18921.0, 19114.617707645768, 19129.90435047816, 19252.22991080341, 19526.609467810566, 7325.743598974011, 7358.264173582599, 7361.2638736125955, 7507.812304692317, 7521.8119547010665, 4050.25070361984, 4092.4884389451317, 4116.191607284268, 4205.726627769185, 4282.272013757669, 2823.68939416664, 3167.920801979969, 3277.6886195811358, 3280.5899262592175, 3282.4748040313434, 599.1761328173761, 603.8490377405649, 606.2149516376303, 611.6116266171014, 622.2408661433044, 569.6325869813946, 601.4406601860225, 611.3031144495279, 623.4420193926411, 625.5246013030039, 7883.172266912006, 8570.0, 8601.698940537051, 8614.61234244448, 8626.353023523261, 535.7080391186812, 558.6508432229856, 566.7308028686424, 566.9574781891374, 592.8340064781868, 4037.6971727119994, 4041.131156801243, 4115.34154535273, 4137.317342638431, 4244.596763307481, 109395.90604093857, 112423.0, 113743.0, 115848.42075789567, 120158.19762703567, 59489.0, 61722.0, 71639.64180179065, 97177.51411242898, 105665.0, 51437.0, 66528.00207996981, 70725.64637176885, 82131.5893420529, 82411.0, 9027.232685221566, 9164.725058247997, 9537.570809313458, 9576.449146162708, 9702.854457183006, 40395.59604404036, 52112.739436302574, 52138.0, 52373.47626523686, 53456.93086138178, 70540.64729676319, 73720.0, 76281.0, 76387.61806190934, 78348.0, 11522.654320370402, 11620.477078531318, 11758.647240582795, 11851.881481185423, 12218.6945326364, 13569.118007329493, 13705.246211458465, 14173.716525669222, 14197.858021420067, 14202.431902723776, 12334.876651233373, 12429.564965226535, 12684.429200685807, 12717.0, 13229.933850330686, 11969.940150299193, 12229.0, 12519.436625351698, 12558.183718058297, 12775.23348599087, 12053.63839084829, 12156.0, 12173.69565760863, 12475.563355282522, 12555.874441255835, 9651.372660777308, 9776.80446391054, 10175.484025177111, 10182.287239893136, 10284.96593504903, 10415.375077495375, 10847.837282440612, 11037.282576632493, 11071.72320691989, 11138.33170009802], "value": 7067.767416503248, "unit": "score"}], "name": "dromaeo_css", "value": 7037.501009699976, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 113.0, 112.0, 115.0, 114.0, 112.0, 114.0, 112.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 107.0, 107.0, 168.0, 133.0, 138.0, 110.0, 112.0, 140.0, 110.0], "value": 126.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 187.0, 186.0, 184.0, 181.0, 185.0, 183.0, 184.0, 186.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 62.0, 58.0, 89.0, 61.0, 60.0, 58.0, 57.0, 80.0, 57.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 95.0, 71.0, 68.0, 69.0, 88.0, 67.0, 69.0, 92.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 85.0, 86.0, 84.0, 88.0, 84.0, 88.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 100.0, 96.0, 96.0, 143.0, 93.0, 152.0, 160.0, 150.0], "value": 118.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 120.0, 97.0, 80.0, 81.0, 80.0, 84.0, 84.0, 87.0, 129.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 56.0, 54.0, 58.0, 58.0, 56.0, 56.0, 56.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 73.0, 66.0, 66.0, 68.0, 70.0, 69.0, 69.0, 72.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 84.0, 83.0, 88.0, 83.0, 83.0, 82.0, 84.0, 83.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 159.0, 154.0, 147.0, 153.0, 160.0, 142.0, 140.0, 190.0, 139.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 162.0, 167.0, 165.0, 163.0, 162.0, 161.0, 164.0, 162.0], "value": 163.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 68.0, 67.0, 63.0, 64.0, 69.0, 67.0, 81.0, 82.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1462.6, "alertThreshold": 2.0}]} 03:20:47 INFO - Return code: 0 03:20:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:20:47 INFO - # TBPL SUCCESS # 03:20:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:20:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:20:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:20:47 INFO - 2017-01-11 03:20:47.964 screenresolution[1667:9890] starting screenresolution argv=screenresolution get 03:20:47 INFO - 2017-01-11 03:20:47.967 screenresolution[1667:9890] Display 0: 1600x1200x32@60 03:20:47 INFO - 2017-01-11 03:20:47.973 screenresolution[1668:9893] starting screenresolution argv=screenresolution list 03:20:47 INFO - Available Modes on Display 0 03:20:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:20:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:20:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:20:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:20:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:20:48 INFO - Intel Iris: 03:20:48 INFO - Chipset Model: Intel Iris 03:20:48 INFO - Type: GPU 03:20:48 INFO - Bus: Built-In 03:20:48 INFO - VRAM (Dynamic, Max): 1536 MB 03:20:48 INFO - Vendor: Intel (0x8086) 03:20:48 INFO - Device ID: 0x0a2e 03:20:48 INFO - Revision ID: 0x0009 03:20:48 INFO - Displays: 03:20:48 INFO - Display: 03:20:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:20:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:20:48 INFO - Display Serial Number: 1600x1200 60 03:20:48 INFO - Main Display: Yes 03:20:48 INFO - Mirror: Off 03:20:48 INFO - Online: Yes 03:20:48 INFO - Rotation: Supported 03:20:48 INFO - Return code: 0 03:20:48 INFO - Running post-action listener: _resource_record_post_action 03:20:48 INFO - [mozharness: 2017-01-11 11:20:48.219992Z] Finished run-tests step (success) 03:20:48 INFO - Running post-run listener: _resource_record_post_run 03:20:48 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37352448; Write bytes: 660297728; Read time: 239; Write time: 1983 03:20:48 INFO - TinderboxPrint: CPU usage
53.6% 03:20:48 INFO - TinderboxPrint: I/O read bytes / time
37,352,448 / 239 03:20:48 INFO - TinderboxPrint: I/O write bytes / time
660,297,728 / 1,983 03:20:48 INFO - TinderboxPrint: CPU idle
2,246.3 (46.4%) 03:20:48 INFO - TinderboxPrint: CPU system
970.2 (20.0%) 03:20:48 INFO - TinderboxPrint: CPU user
1,629.2 (33.6%) 03:20:48 INFO - TinderboxPrint: Swap in / out
400,175,104 / 0 03:20:48 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 333541888; Write bytes: 333252608; Read time: 25740; Write time: 728 03:20:48 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36377600; Write bytes: 309338112; Read time: 226; Write time: 1199 03:20:48 INFO - Running post-run listener: _upload_blobber_files 03:20:48 INFO - Blob upload gear active. 03:20:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:20:48 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 03:20:48 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'] 03:20:48 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 03:20:49 INFO - (blobuploader) - INFO - Open directory for files ... 03:20:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:20:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:20:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:50 INFO - (blobuploader) - INFO - Done attempting. 03:20:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:20:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:20:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:51 INFO - (blobuploader) - INFO - Done attempting. 03:20:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:20:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:20:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:54 INFO - (blobuploader) - INFO - Done attempting. 03:20:54 INFO - (blobuploader) - INFO - Iteration through files over. 03:20:54 INFO - Return code: 0 03:20:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:20:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:20:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb93907eeb466414b2eee421462330bfe1761d7ecb877a93d40b0ed05b03c7461ccfbb0704fd80d0394b4c5ba3bb73248bcd79430f3e37d6a51cbba31862cd9c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49c992b18bd867cf83b1ab8dbdf67bfd019244f41ecd1ea4542ea10ba997a33374ac009d1fe5b145e00e58539c0fb0f38326b660bb2371732410d6987e0ecd0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b2e75d8522738687df16841a5df5d9389ded706d85955b09ae75b17b6e7f0de9096b55b76717e94c9e1eea168b12d6f4be9c59b155b471741216520f41fa142b"} 03:20:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:20:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:20:54 INFO - Contents: 03:20:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb93907eeb466414b2eee421462330bfe1761d7ecb877a93d40b0ed05b03c7461ccfbb0704fd80d0394b4c5ba3bb73248bcd79430f3e37d6a51cbba31862cd9c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49c992b18bd867cf83b1ab8dbdf67bfd019244f41ecd1ea4542ea10ba997a33374ac009d1fe5b145e00e58539c0fb0f38326b660bb2371732410d6987e0ecd0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b2e75d8522738687df16841a5df5d9389ded706d85955b09ae75b17b6e7f0de9096b55b76717e94c9e1eea168b12d6f4be9c59b155b471741216520f41fa142b"} 03:20:54 INFO - Running post-run listener: copy_logs_to_upload_dir 03:20:54 INFO - Copying logs to upload dir... 03:20:54 INFO - mkdir: /builds/slave/test/build/upload/logs 03:20:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.559797 ========= master_lag: 0.70 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-01-11 03:20:54.848211) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 03:20:54.851986) ========= 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.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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/cb93907eeb466414b2eee421462330bfe1761d7ecb877a93d40b0ed05b03c7461ccfbb0704fd80d0394b4c5ba3bb73248bcd79430f3e37d6a51cbba31862cd9c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49c992b18bd867cf83b1ab8dbdf67bfd019244f41ecd1ea4542ea10ba997a33374ac009d1fe5b145e00e58539c0fb0f38326b660bb2371732410d6987e0ecd0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b2e75d8522738687df16841a5df5d9389ded706d85955b09ae75b17b6e7f0de9096b55b76717e94c9e1eea168b12d6f4be9c59b155b471741216520f41fa142b"} build_url:https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011191 build_url: 'https://queue.taskcluster.net/v1/task/SssT9ElXRHSi6_itCi9A0g/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/cb93907eeb466414b2eee421462330bfe1761d7ecb877a93d40b0ed05b03c7461ccfbb0704fd80d0394b4c5ba3bb73248bcd79430f3e37d6a51cbba31862cd9c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49c992b18bd867cf83b1ab8dbdf67bfd019244f41ecd1ea4542ea10ba997a33374ac009d1fe5b145e00e58539c0fb0f38326b660bb2371732410d6987e0ecd0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b2e75d8522738687df16841a5df5d9389ded706d85955b09ae75b17b6e7f0de9096b55b76717e94c9e1eea168b12d6f4be9c59b155b471741216520f41fa142b"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 03:20:54.913649) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 03:20:54.914075) ========= 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.R6ZzvYVZBO/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.OcVQqzqZQN/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.004969 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 03:20:54.968638) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 03:20:54.968984) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 03:20:54.978521) ========= ========= Total master_lag: 0.88 =========