builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0382 starttime: 1484220066.02 results: success (0) buildid: 20170112004017 builduid: 478b5aa5cf0243f39af9c10f09cd9120 revision: 1abb4b193740653f1161d86f38dbb79287e7d69b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 03:21:06.018434) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 03:21:06.018834) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 03:21:06.019124) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 03:21:06.047568) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 03:21:06.047920) ========= 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.0swuVamk4i/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.PLPy4gnyzN/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-12 03:21:06-- 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% 143M=0s 2017-01-12 03:21:06 (143 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133078 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 03:21:06.204195) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 03:21:06.204552) ========= 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.0swuVamk4i/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.PLPy4gnyzN/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.021356 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 03:21:06.252878) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-12 03:21:06.253251) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1abb4b193740653f1161d86f38dbb79287e7d69b --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 1abb4b193740653f1161d86f38dbb79287e7d69b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0swuVamk4i/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.PLPy4gnyzN/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-12 03:21:06,334 truncating revision to first 12 chars 2017-01-12 03:21:06,334 Setting DEBUG logging. 2017-01-12 03:21:06,334 attempt 1/10 2017-01-12 03:21:06,334 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1abb4b193740?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-12 03:21:08,498 unpacking tar archive at: mozilla-aurora-1abb4b193740/testing/mozharness/ program finished with exit code 0 elapsedTime=2.440582 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-12 03:21:08.717585) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 03:21:08.717901) ========= script_repo_revision: 1abb4b193740653f1161d86f38dbb79287e7d69b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 03:21:08.718299) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 03:21:08.718566) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 03:21:08.738432) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-12 03:21:08.738732) ========= /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.0swuVamk4i/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.PLPy4gnyzN/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 03:21:08 INFO - MultiFileLogger online at 20170112 03:21:08 in /builds/slave/test 03:21:08 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 03:21:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:21:08 INFO - {'append_to_log': False, 03:21:08 INFO - 'base_work_dir': '/builds/slave/test', 03:21:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:21:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:21:08 INFO - 'branch': 'Mozilla-Aurora', 03:21:08 INFO - 'buildbot_json_path': 'buildprops.json', 03:21:08 INFO - 'config_files': ('talos/mac_config.py',), 03:21:08 INFO - 'default_actions': ('clobber', 03:21:08 INFO - 'read-buildbot-config', 03:21:08 INFO - 'download-and-extract', 03:21:08 INFO - 'populate-webroot', 03:21:08 INFO - 'create-virtualenv', 03:21:08 INFO - 'install', 03:21:08 INFO - 'run-tests'), 03:21:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:21:08 INFO - 'download_minidump_stackwalk': True, 03:21:08 INFO - 'download_symbols': 'ondemand', 03:21:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:21:08 INFO - 'tooltool.py': '/tools/tooltool.py', 03:21:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:21:08 INFO - '/tools/misc-python/virtualenv.py')}, 03:21:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:21:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:21:08 INFO - 'installer_path': 'installer.exe', 03:21:08 INFO - 'log_level': 'info', 03:21:08 INFO - 'log_name': 'talos', 03:21:08 INFO - 'log_to_console': True, 03:21:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:21:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:21:08 INFO - 'opt_config_files': (), 03:21:08 INFO - 'pip_index': False, 03:21:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:21:08 INFO - 'cmd': ('bash', 03:21:08 INFO - '-c', 03:21:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:21:08 INFO - 'enabled': True, 03:21:08 INFO - 'halt_on_failure': False, 03:21:08 INFO - 'name': 'check_screen_resolution'},), 03:21:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:21:08 INFO - 'cmd': ('bash', 03:21:08 INFO - '-c', 03:21:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:21:08 INFO - 'enabled': True, 03:21:08 INFO - 'halt_on_failure': False, 03:21:08 INFO - 'name': 'check_screen_resolution'},), 03:21:08 INFO - 'run_cmd_checks_enabled': True, 03:21:08 INFO - 'sps_profile': False, 03:21:08 INFO - 'sps_profile_interval': 0, 03:21:08 INFO - 'suite': 'dromaeojs', 03:21:08 INFO - 'system_bits': '32', 03:21:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:21:08 INFO - 'title': 't-yosemite-r7-0382', 03:21:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:21:08 INFO - 'use_talos_json': True, 03:21:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:21:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:21:08 INFO - 'work_dir': 'build'} 03:21:08 INFO - [mozharness: 2017-01-12 11:21:08.916751Z] Running clobber step. 03:21:08 INFO - Running pre-action listener: _resource_record_pre_action 03:21:08 INFO - Running main action method: clobber 03:21:08 INFO - rmtree: /builds/slave/test/build 03:21:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:21:09 INFO - Running post-action listener: _resource_record_post_action 03:21:09 INFO - [mozharness: 2017-01-12 11:21:09.151537Z] Finished clobber step (success) 03:21:09 INFO - [mozharness: 2017-01-12 11:21:09.151749Z] Running read-buildbot-config step. 03:21:09 INFO - Running pre-action listener: _resource_record_pre_action 03:21:09 INFO - Running main action method: read_buildbot_config 03:21:09 INFO - Using buildbot properties: 03:21:09 INFO - { 03:21:09 INFO - "project": "", 03:21:09 INFO - "product": "firefox", 03:21:09 INFO - "script_repo_revision": "production", 03:21:09 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:21:09 INFO - "repository": "", 03:21:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:21:09 INFO - "buildid": "20170112004017", 03:21:09 INFO - "stage_platform": "macosx64", 03:21:09 INFO - "basedir": "/builds/slave/test", 03:21:09 INFO - "nightly_build": "True", 03:21:09 INFO - "buildnumber": 65, 03:21:09 INFO - "slavename": "t-yosemite-r7-0382", 03:21:09 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:21:09 INFO - "platform": "yosemite_r7", 03:21:09 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:21:09 INFO - "branch": "mozilla-aurora", 03:21:09 INFO - "repo_path": "releases/mozilla-aurora", 03:21:09 INFO - "revision": "1abb4b193740653f1161d86f38dbb79287e7d69b", 03:21:09 INFO - "pgo_build": "False", 03:21:09 INFO - "builduid": "478b5aa5cf0243f39af9c10f09cd9120", 03:21:09 INFO - "slavebuilddir": "test" 03:21:09 INFO - } 03:21:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:21:09 INFO - Running post-action listener: _resource_record_post_action 03:21:09 INFO - [mozharness: 2017-01-12 11:21:09.157499Z] Finished read-buildbot-config step (success) 03:21:09 INFO - [mozharness: 2017-01-12 11:21:09.157725Z] Running download-and-extract step. 03:21:09 INFO - Running pre-action listener: _resource_record_pre_action 03:21:09 INFO - Running main action method: download_and_extract 03:21:09 INFO - mkdir: /builds/slave/test/build/tests 03:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:09 INFO - trying https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:21:09 INFO - Downloading https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/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 03:21:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/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 03:21:10 INFO - Downloaded 1199 bytes. 03:21:10 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:21:10 INFO - Using the following test package requirements: 03:21:10 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:21:10 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:10 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:21:10 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:10 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:21:10 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:10 INFO - u'jsshell-mac.zip'], 03:21:10 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:10 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:21:10 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:21:10 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:10 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:21:10 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:10 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:21:10 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:10 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:21:10 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:10 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:21:10 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:21:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:21:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:21:10 INFO - Fetch https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:21:11 INFO - Http code: 200 03:21:11 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 03:21:11 INFO - x-amz-cf-id: QpMgBW942EcktQi1yKhql_S1l-uQ_pu3vPkWsGvX76QrhpuCc7Qk6w== 03:21:11 INFO - x-amz-version-id: 2Ss.t6iOr.3DWp6vwgHFMOpo9Akgin7V 03:21:11 INFO - Expected file size: 16273047 03:21:11 INFO - Obtained file size: 16273047 03:21:18 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 03:21:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:21:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:21:18 INFO - Fetch https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:21:18 INFO - Http code: 200 03:21:18 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 03:21:18 INFO - x-amz-cf-id: kUj2j5jySFMvrf_ZmwwS_zaoqiwj8bxKT4mcMWno38R49Rb4z6Cyhg== 03:21:18 INFO - x-amz-version-id: 2Ss.t6iOr.3DWp6vwgHFMOpo9Akgin7V 03:21:19 INFO - Expected file size: 16273047 03:21:19 INFO - Obtained file size: 16273047 03:21:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:21:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:21:25 INFO - Fetch https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:21:25 INFO - Http code: 200 03:21:25 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 03:21:25 INFO - x-amz-cf-id: vI9dsZnbZxZbtWjhMVKUsA8lpkNo8WTZ0IUpqd36PKrTh-vlNECxWA== 03:21:25 INFO - x-amz-version-id: ES1XSjTQqHEpuHEmUTjDQMZ6KjrfI02X 03:21:26 INFO - Expected file size: 14409038 03:21:26 INFO - Obtained file size: 14409038 03:21:26 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:21:26 INFO - trying https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:21:26 INFO - Downloading https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:21:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/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:21:29 INFO - Downloaded 110039841 bytes. 03:21:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:21:29 INFO - mkdir: /builds/slave/test/properties 03:21:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:21:29 INFO - Writing to file /builds/slave/test/properties/build_url 03:21:29 INFO - Contents: 03:21:29 INFO - build_url:https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:21:30 INFO - Running post-action listener: _resource_record_post_action 03:21:30 INFO - Running post-action listener: set_extra_try_arguments 03:21:30 INFO - [mozharness: 2017-01-12 11:21:30.026322Z] Finished download-and-extract step (success) 03:21:30 INFO - [mozharness: 2017-01-12 11:21:30.026447Z] Running populate-webroot step. 03:21:30 INFO - Running pre-action listener: _resource_record_pre_action 03:21:30 INFO - Running main action method: populate_webroot 03:21:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:21:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:21:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:21:30 INFO - '--tppagecycles', 03:21:30 INFO - '7'], 03:21:30 INFO - 'tests': ['tsvgm']}}, 03:21:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:21:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:21:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:21:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:21:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['damp', 'tps']}, 03:21:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'tests': ['damp', 'tps']}, 03:21:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['dromaeo_dom']}, 03:21:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:21:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['basic_compositor_video']}, 03:21:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:21:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['a11yr', 03:21:30 INFO - 'ts_paint', 03:21:30 INFO - 'tpaint', 03:21:30 INFO - 'sessionrestore', 03:21:30 INFO - 'sessionrestore_no_auto_restore', 03:21:30 INFO - 'tabpaint']}, 03:21:30 INFO - 'other-e10s': {'tests': ['a11yr', 03:21:30 INFO - 'ts_paint', 03:21:30 INFO - 'tpaint', 03:21:30 INFO - 'sessionrestore', 03:21:30 INFO - 'sessionrestore_no_auto_restore', 03:21:30 INFO - 'tabpaint']}, 03:21:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:21:30 INFO - 'ts_paint', 03:21:30 INFO - 'tpaint', 03:21:30 INFO - 'sessionrestore', 03:21:30 INFO - 'sessionrestore_no_auto_restore', 03:21:30 INFO - 'tabpaint']}, 03:21:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:21:30 INFO - 'ts_paint', 03:21:30 INFO - 'tpaint', 03:21:30 INFO - 'sessionrestore', 03:21:30 INFO - 'sessionrestore_no_auto_restore', 03:21:30 INFO - 'tabpaint']}, 03:21:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['a11yr', 03:21:30 INFO - 'ts_paint', 03:21:30 INFO - 'tpaint', 03:21:30 INFO - 'sessionrestore', 03:21:30 INFO - 'sessionrestore_no_auto_restore', 03:21:30 INFO - 'tabpaint']}, 03:21:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['a11yr', 03:21:30 INFO - 'ts_paint', 03:21:30 INFO - 'tpaint', 03:21:30 INFO - 'sessionrestore', 03:21:30 INFO - 'sessionrestore_no_auto_restore', 03:21:30 INFO - 'tabpaint']}, 03:21:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['tsvgx', 03:21:30 INFO - 'tsvgr_opacity', 03:21:30 INFO - 'tart', 03:21:30 INFO - 'tscrollx', 03:21:30 INFO - 'cart']}, 03:21:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:21:30 INFO - 'tsvgr_opacity', 03:21:30 INFO - 'tart', 03:21:30 INFO - 'tscrollx', 03:21:30 INFO - 'cart']}, 03:21:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['tp5o']}, 03:21:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'tests': ['tp5o']}, 03:21:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'talos_options': ['--disable-e10s', 03:21:30 INFO - '--xperf_path', 03:21:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:30 INFO - 'tests': ['tp5n']}, 03:21:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'talos_options': ['--xperf_path', 03:21:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:30 INFO - 'tests': ['tp5n']}}, 03:21:30 INFO - 'talos.zip': {'path': '', 03:21:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:21:30 INFO - Running post-action listener: _resource_record_post_action 03:21:30 INFO - [mozharness: 2017-01-12 11:21:30.045353Z] Finished populate-webroot step (success) 03:21:30 INFO - [mozharness: 2017-01-12 11:21:30.045495Z] Running create-virtualenv step. 03:21:30 INFO - Running pre-action listener: _resource_record_pre_action 03:21:30 INFO - Running main action method: create_virtualenv 03:21:30 INFO - Creating virtualenv /builds/slave/test/build/venv 03:21:30 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:21:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:21:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:21:30 INFO - Using real prefix '/tools/python27' 03:21:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:21:30 INFO - Installing distribute.............................................................................................................................................................................................done. 03:21:33 INFO - Installing pip.................done. 03:21:33 INFO - Return code: 0 03:21:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:21:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:21:33 INFO - Reading from file tmpfile_stdout 03:21:33 INFO - Output received: 03:21:33 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:21:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:21:33 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:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:33 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:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:33 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 0x10ecba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404bb2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaced30>, '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 0x10e934db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eac6b20>, '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.0swuVamk4i/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.PLPy4gnyzN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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:21:33 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:21:33 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:21:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0swuVamk4i/Render', 03:21:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:33 INFO - 'HOME': '/Users/cltbld', 03:21:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:33 INFO - 'LOGNAME': 'cltbld', 03:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:33 INFO - 'MOZ_NO_REMOTE': '1', 03:21:33 INFO - 'NO_EM_RESTART': '1', 03:21:33 INFO - 'PAGER': '/bin/cat', 03:21:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:33 INFO - 'PWD': '/builds/slave/test', 03:21:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:33 INFO - 'SHELL': '/bin/bash', 03:21:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLPy4gnyzN/Listeners', 03:21:33 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 03:21:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:33 INFO - 'USER': 'cltbld', 03:21:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:33 INFO - 'XPC_FLAGS': '0x0', 03:21:33 INFO - 'XPC_SERVICE_NAME': '0', 03:21:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:34 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:21:34 INFO - Cleaning up... 03:21:34 INFO - Return code: 0 03:21:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:21:34 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:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:34 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:21:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:34 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 0x10ecba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404bb2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaced30>, '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 0x10e934db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eac6b20>, '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.0swuVamk4i/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.PLPy4gnyzN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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:21:34 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:21:34 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:21:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0swuVamk4i/Render', 03:21:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:34 INFO - 'HOME': '/Users/cltbld', 03:21:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:34 INFO - 'LOGNAME': 'cltbld', 03:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:34 INFO - 'MOZ_NO_REMOTE': '1', 03:21:34 INFO - 'NO_EM_RESTART': '1', 03:21:34 INFO - 'PAGER': '/bin/cat', 03:21:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:34 INFO - 'PWD': '/builds/slave/test', 03:21:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:34 INFO - 'SHELL': '/bin/bash', 03:21:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLPy4gnyzN/Listeners', 03:21:34 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 03:21:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:34 INFO - 'USER': 'cltbld', 03:21:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:34 INFO - 'XPC_FLAGS': '0x0', 03:21:34 INFO - 'XPC_SERVICE_NAME': '0', 03:21:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:34 INFO - Downloading/unpacking psutil>=3.1.1 03:21:34 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:21:34 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:21:34 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:21:34 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:21:34 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:21:34 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:21:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:21:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:37 INFO - Installing collected packages: psutil 03:21:37 INFO - Running setup.py install for psutil 03:21:38 INFO - building 'psutil._psutil_osx' extension 03:21:38 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:21:38 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:21:38 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:21:38 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:21:38 INFO - building 'psutil._psutil_posix' extension 03:21:38 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:21:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:21:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:21:38 INFO - ^ 03:21:38 INFO - 1 warning generated. 03:21:38 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:21:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:38 INFO - Successfully installed psutil 03:21:38 INFO - Cleaning up... 03:21:38 INFO - Return code: 0 03:21:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:21:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:38 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 0x10ecba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404bb2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaced30>, '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 0x10e934db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eac6b20>, '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.0swuVamk4i/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.PLPy4gnyzN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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:21:38 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:21:38 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:21:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0swuVamk4i/Render', 03:21:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:38 INFO - 'HOME': '/Users/cltbld', 03:21:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:38 INFO - 'LOGNAME': 'cltbld', 03:21:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:38 INFO - 'MOZ_NO_REMOTE': '1', 03:21:38 INFO - 'NO_EM_RESTART': '1', 03:21:38 INFO - 'PAGER': '/bin/cat', 03:21:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:38 INFO - 'PWD': '/builds/slave/test', 03:21:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:38 INFO - 'SHELL': '/bin/bash', 03:21:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLPy4gnyzN/Listeners', 03:21:38 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 03:21:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:38 INFO - 'USER': 'cltbld', 03:21:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:38 INFO - 'XPC_FLAGS': '0x0', 03:21:38 INFO - 'XPC_SERVICE_NAME': '0', 03:21:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:21:39 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:21:39 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:21:39 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:21:39 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:21:39 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:21:39 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:21:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:21:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:21:41 INFO - Installing collected packages: mozsystemmonitor 03:21:41 INFO - Running setup.py install for mozsystemmonitor 03:21:41 INFO - Successfully installed mozsystemmonitor 03:21:41 INFO - Cleaning up... 03:21:42 INFO - Return code: 0 03:21:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:21:42 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:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:42 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:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:42 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 0x10ecba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404bb2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaced30>, '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 0x10e934db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eac6b20>, '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.0swuVamk4i/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.PLPy4gnyzN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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:21:42 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:21:42 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:21:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0swuVamk4i/Render', 03:21:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:42 INFO - 'HOME': '/Users/cltbld', 03:21:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:42 INFO - 'LOGNAME': 'cltbld', 03:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:42 INFO - 'MOZ_NO_REMOTE': '1', 03:21:42 INFO - 'NO_EM_RESTART': '1', 03:21:42 INFO - 'PAGER': '/bin/cat', 03:21:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:42 INFO - 'PWD': '/builds/slave/test', 03:21:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:42 INFO - 'SHELL': '/bin/bash', 03:21:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLPy4gnyzN/Listeners', 03:21:42 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 03:21:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:42 INFO - 'USER': 'cltbld', 03:21:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:42 INFO - 'XPC_FLAGS': '0x0', 03:21:42 INFO - 'XPC_SERVICE_NAME': '0', 03:21:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:42 INFO - Downloading/unpacking jsonschema==2.5.1 03:21:42 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:21:42 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:21:42 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:21:42 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:21:42 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:21:42 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:21:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:21:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:45 INFO - Downloading functools32-3.2.3-2.tar.gz 03:21:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:21:45 INFO - warning: no files found matching '*.txt' 03:21:45 INFO - no previously-included directories found matching 'build' 03:21:45 INFO - no previously-included directories found matching 'dist' 03:21:45 INFO - no previously-included directories found matching '.git*' 03:21:45 INFO - Installing collected packages: jsonschema, functools32 03:21:45 INFO - Running setup.py install for functools32 03:21:45 INFO - warning: no files found matching '*.txt' 03:21:45 INFO - no previously-included directories found matching 'build' 03:21:45 INFO - no previously-included directories found matching 'dist' 03:21:45 INFO - no previously-included directories found matching '.git*' 03:21:45 INFO - Successfully installed jsonschema functools32 03:21:45 INFO - Cleaning up... 03:21:45 INFO - Return code: 0 03:21:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:21:45 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:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:45 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:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:45 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 0x10ecba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404bb2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaced30>, '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 0x10e934db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eac6b20>, '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.0swuVamk4i/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.PLPy4gnyzN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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:21:45 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:21:45 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:21:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0swuVamk4i/Render', 03:21:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:45 INFO - 'HOME': '/Users/cltbld', 03:21:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:45 INFO - 'LOGNAME': 'cltbld', 03:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:45 INFO - 'MOZ_NO_REMOTE': '1', 03:21:45 INFO - 'NO_EM_RESTART': '1', 03:21:45 INFO - 'PAGER': '/bin/cat', 03:21:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:45 INFO - 'PWD': '/builds/slave/test', 03:21:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:45 INFO - 'SHELL': '/bin/bash', 03:21:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLPy4gnyzN/Listeners', 03:21:45 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 03:21:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:45 INFO - 'USER': 'cltbld', 03:21:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:45 INFO - 'XPC_FLAGS': '0x0', 03:21:45 INFO - 'XPC_SERVICE_NAME': '0', 03:21:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:21:45 INFO - Cleaning up... 03:21:45 INFO - Return code: 0 03:21:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:21:45 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:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:45 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:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:45 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 0x10ecba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404bb2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaced30>, '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 0x10e934db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eac6b20>, '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.0swuVamk4i/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.PLPy4gnyzN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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:21:45 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:21:45 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:21:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0swuVamk4i/Render', 03:21:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:45 INFO - 'HOME': '/Users/cltbld', 03:21:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:45 INFO - 'LOGNAME': 'cltbld', 03:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:45 INFO - 'MOZ_NO_REMOTE': '1', 03:21:45 INFO - 'NO_EM_RESTART': '1', 03:21:45 INFO - 'PAGER': '/bin/cat', 03:21:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:45 INFO - 'PWD': '/builds/slave/test', 03:21:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:45 INFO - 'SHELL': '/bin/bash', 03:21:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLPy4gnyzN/Listeners', 03:21:45 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 03:21:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:45 INFO - 'USER': 'cltbld', 03:21:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:45 INFO - 'XPC_FLAGS': '0x0', 03:21:45 INFO - 'XPC_SERVICE_NAME': '0', 03:21:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:46 INFO - Downloading/unpacking blobuploader==1.2.4 03:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:48 INFO - Downloading blobuploader-1.2.4.tar.gz 03:21:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:21:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:21:48 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:21:48 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:21:48 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:21:48 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:21:48 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:21:48 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:21:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:21:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:21:49 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:21:49 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:21:49 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:21:49 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:21:49 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:21:49 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:21:49 INFO - Downloading docopt-0.6.1.tar.gz 03:21:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:21:50 INFO - Installing collected packages: blobuploader, requests, docopt 03:21:50 INFO - Running setup.py install for blobuploader 03:21:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:21:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:21:50 INFO - Running setup.py install for requests 03:21:50 INFO - Running setup.py install for docopt 03:21:50 INFO - Successfully installed blobuploader requests docopt 03:21:50 INFO - Cleaning up... 03:21:50 INFO - Return code: 0 03:21:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:50 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:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:50 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:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:50 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 0x10ecba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404bb2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaced30>, '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 0x10e934db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eac6b20>, '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.0swuVamk4i/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.PLPy4gnyzN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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:21:50 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:21:50 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:21:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0swuVamk4i/Render', 03:21:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:50 INFO - 'HOME': '/Users/cltbld', 03:21:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:50 INFO - 'LOGNAME': 'cltbld', 03:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:50 INFO - 'MOZ_NO_REMOTE': '1', 03:21:50 INFO - 'NO_EM_RESTART': '1', 03:21:50 INFO - 'PAGER': '/bin/cat', 03:21:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:50 INFO - 'PWD': '/builds/slave/test', 03:21:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:50 INFO - 'SHELL': '/bin/bash', 03:21:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLPy4gnyzN/Listeners', 03:21:50 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 03:21:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:50 INFO - 'USER': 'cltbld', 03:21:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:50 INFO - 'XPC_FLAGS': '0x0', 03:21:50 INFO - 'XPC_SERVICE_NAME': '0', 03:21:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:51 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-MDe1G8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:51 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-xuJILT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:51 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Z0lOtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:21:51 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-TZdq6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:21:51 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-3YaSis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:21:51 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-LOweK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:21:51 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-nq08Bv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:21:51 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-HterPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:21:52 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-HDwixD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:21:52 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-k7cVW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:21:52 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Ji2oMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:21:52 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-CnZUeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:21:52 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-JleQtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:21:52 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-jTODns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:52 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-8q9Cc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:21:52 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-vHpLDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:21:52 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-QLyn34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:21:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:21:53 INFO - Running setup.py install for manifestparser 03:21:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:21:53 INFO - Running setup.py install for mozcrash 03:21:53 INFO - Running setup.py install for mozdebug 03:21:53 INFO - Running setup.py install for mozdevice 03:21:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:21:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:21:53 INFO - Running setup.py install for mozfile 03:21:53 INFO - Running setup.py install for mozhttpd 03:21:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:21:53 INFO - Running setup.py install for mozinfo 03:21:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:21:54 INFO - Running setup.py install for mozInstall 03:21:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:21:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:21:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:21:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:21:54 INFO - Running setup.py install for mozleak 03:21:54 INFO - Running setup.py install for mozlog 03:21:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:21:54 INFO - Running setup.py install for moznetwork 03:21:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:21:54 INFO - Running setup.py install for mozprocess 03:21:54 INFO - Running setup.py install for mozprofile 03:21:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:21:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:21:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:21:54 INFO - Running setup.py install for mozrunner 03:21:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:21:55 INFO - Running setup.py install for mozscreenshot 03:21:55 INFO - Running setup.py install for moztest 03:21:55 INFO - Running setup.py install for mozversion 03:21:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:21:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:21:55 INFO - Cleaning up... 03:21:55 INFO - Return code: 0 03:21:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:55 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:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:55 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:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:55 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 0x10ecba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404bb2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaced30>, '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 0x10e934db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eac6b20>, '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.0swuVamk4i/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.PLPy4gnyzN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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:21:55 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:21:55 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:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0swuVamk4i/Render', 03:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:55 INFO - 'HOME': '/Users/cltbld', 03:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:55 INFO - 'LOGNAME': 'cltbld', 03:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:55 INFO - 'MOZ_NO_REMOTE': '1', 03:21:55 INFO - 'NO_EM_RESTART': '1', 03:21:55 INFO - 'PAGER': '/bin/cat', 03:21:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:55 INFO - 'PWD': '/builds/slave/test', 03:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:55 INFO - 'SHELL': '/bin/bash', 03:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLPy4gnyzN/Listeners', 03:21:55 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 03:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:55 INFO - 'USER': 'cltbld', 03:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:55 INFO - 'XPC_FLAGS': '0x0', 03:21:55 INFO - 'XPC_SERVICE_NAME': '0', 03:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:55 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-QgmBFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:55 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:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:55 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-4X7Yye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:56 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:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-XUu58M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:56 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:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:21:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-oULNUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:21:56 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:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:21:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-vFDtxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:21:56 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:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:21:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-zNEzju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:21:56 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:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:21:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-AkyIhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:21:56 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:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:21:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-GNgn3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:21:56 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:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:21:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-DtbiJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:21:56 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:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:21:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-5PsNI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:21:56 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:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:21:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-gh6Axb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:21:57 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:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:21:57 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-mACSgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:21:57 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:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:21:57 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-CTzmo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:21:57 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:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:21:57 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-leCo_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:21:57 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:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:57 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-8gz8RV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:57 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:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:21:57 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-rXzAw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:21:57 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:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:21:57 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-BnV2CN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:21:57 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:21:57 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:21:57 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:21:57 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:21:57 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:21:57 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:21:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:21:57 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:21:57 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:21:57 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:21:57 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:21:57 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:21:57 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:22:00 INFO - Downloading blessings-1.6.tar.gz 03:22:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:22:00 INFO - Installing collected packages: blessings 03:22:00 INFO - Running setup.py install for blessings 03:22:00 INFO - Successfully installed blessings 03:22:00 INFO - Cleaning up... 03:22:00 INFO - Return code: 0 03:22:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:22:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:00 INFO - Reading from file tmpfile_stdout 03:22:00 INFO - Current package versions: 03:22:00 INFO - blessings == 1.6 03:22:00 INFO - blobuploader == 1.2.4 03:22:00 INFO - docopt == 0.6.1 03:22:00 INFO - functools32 == 3.2.3-2 03:22:00 INFO - jsonschema == 2.5.1 03:22:00 INFO - manifestparser == 1.1 03:22:00 INFO - mozInstall == 1.12 03:22:00 INFO - mozcrash == 1.0 03:22:00 INFO - mozdebug == 0.1 03:22:00 INFO - mozdevice == 0.48 03:22:00 INFO - mozfile == 1.2 03:22:00 INFO - mozhttpd == 0.7 03:22:00 INFO - mozinfo == 0.9 03:22:00 INFO - mozleak == 0.1 03:22:00 INFO - mozlog == 3.4 03:22:00 INFO - moznetwork == 0.27 03:22:00 INFO - mozprocess == 0.23 03:22:00 INFO - mozprofile == 0.28 03:22:00 INFO - mozrunner == 6.13 03:22:00 INFO - mozscreenshot == 0.1 03:22:00 INFO - mozsystemmonitor == 0.3 03:22:00 INFO - moztest == 0.8 03:22:00 INFO - mozversion == 1.4 03:22:00 INFO - psutil == 3.1.1 03:22:00 INFO - requests == 1.2.3 03:22:00 INFO - wsgiref == 0.1.2 03:22:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:01 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:22:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:02 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 0x10ecba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404bb2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaced30>, '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 0x10e934db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eac6b20>, '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.0swuVamk4i/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.PLPy4gnyzN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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:22:02 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:22:02 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:22:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0swuVamk4i/Render', 03:22:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:02 INFO - 'HOME': '/Users/cltbld', 03:22:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:02 INFO - 'LOGNAME': 'cltbld', 03:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:02 INFO - 'MOZ_NO_REMOTE': '1', 03:22:02 INFO - 'NO_EM_RESTART': '1', 03:22:02 INFO - 'PAGER': '/bin/cat', 03:22:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:02 INFO - 'PWD': '/builds/slave/test', 03:22:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:02 INFO - 'SHELL': '/bin/bash', 03:22:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLPy4gnyzN/Listeners', 03:22:02 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 03:22:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:02 INFO - 'USER': 'cltbld', 03:22:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:02 INFO - 'XPC_FLAGS': '0x0', 03:22:02 INFO - 'XPC_SERVICE_NAME': '0', 03:22:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:02 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:22:02 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:22:02 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:22:02 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:22:02 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:22:02 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:22:02 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:22:02 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:22:02 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:22:02 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:22:02 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:22:02 INFO - Cleaning up... 03:22:02 INFO - Return code: 0 03:22:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:22:02 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:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:02 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:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:02 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 0x10ecba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404bb2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaced30>, '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 0x10e934db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eac6b20>, '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.0swuVamk4i/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.PLPy4gnyzN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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:22:02 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:22:02 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:22:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0swuVamk4i/Render', 03:22:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:02 INFO - 'HOME': '/Users/cltbld', 03:22:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:02 INFO - 'LOGNAME': 'cltbld', 03:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:02 INFO - 'MOZ_NO_REMOTE': '1', 03:22:02 INFO - 'NO_EM_RESTART': '1', 03:22:02 INFO - 'PAGER': '/bin/cat', 03:22:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:02 INFO - 'PWD': '/builds/slave/test', 03:22:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:02 INFO - 'SHELL': '/bin/bash', 03:22:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLPy4gnyzN/Listeners', 03:22:02 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 03:22:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:02 INFO - 'USER': 'cltbld', 03:22:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:02 INFO - 'XPC_FLAGS': '0x0', 03:22:02 INFO - 'XPC_SERVICE_NAME': '0', 03:22:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:22:03 INFO - Cleaning up... 03:22:03 INFO - Return code: 0 03:22:03 INFO - Running post-action listener: _resource_record_post_action 03:22:03 INFO - Running post-action listener: _start_resource_monitoring 03:22:03 INFO - Starting resource monitoring. 03:22:03 INFO - [mozharness: 2017-01-12 11:22:03.164266Z] Finished create-virtualenv step (success) 03:22:03 INFO - [mozharness: 2017-01-12 11:22:03.165262Z] Running install step. 03:22:03 INFO - Running pre-action listener: _resource_record_pre_action 03:22:03 INFO - Running main action method: install 03:22:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:03 INFO - Reading from file tmpfile_stdout 03:22:03 INFO - Detecting whether we're running mozinstall >=1.0... 03:22:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:22:03 INFO - Reading from file tmpfile_stdout 03:22:03 INFO - Output received: 03:22:03 INFO - Usage: mozinstall [options] installer 03:22:03 INFO - Options: 03:22:03 INFO - -h, --help show this help message and exit 03:22:03 INFO - -d DEST, --destination=DEST 03:22:03 INFO - Directory to install application into. [default: 03:22:03 INFO - "/builds/slave/test"] 03:22:03 INFO - --app=APP Application being installed. [default: firefox] 03:22:03 INFO - mkdir: /builds/slave/test/build/application 03:22:03 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:22:03 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:22:34 INFO - Reading from file tmpfile_stdout 03:22:34 INFO - Output received: 03:22:34 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:22:34 INFO - Running post-action listener: _resource_record_post_action 03:22:34 INFO - [mozharness: 2017-01-12 11:22:34.335305Z] Finished install step (success) 03:22:34 INFO - [mozharness: 2017-01-12 11:22:34.335467Z] Running run-tests step. 03:22:34 INFO - Running pre-action listener: _resource_record_pre_action 03:22:34 INFO - Running main action method: run_tests 03:22:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:22:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:22:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:22:34 INFO - 2017-01-12 03:22:34.350 screenresolution[1923:9219] starting screenresolution argv=screenresolution get 03:22:34 INFO - 2017-01-12 03:22:34.352 screenresolution[1923:9219] Display 0: 1600x1200x32@60 03:22:34 INFO - 2017-01-12 03:22:34.358 screenresolution[1924:9222] starting screenresolution argv=screenresolution list 03:22:34 INFO - Available Modes on Display 0 03:22:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:22:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:22:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:22:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:22:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:22:34 INFO - Intel Iris: 03:22:34 INFO - Chipset Model: Intel Iris 03:22:34 INFO - Type: GPU 03:22:34 INFO - Bus: Built-In 03:22:34 INFO - VRAM (Dynamic, Max): 1536 MB 03:22:34 INFO - Vendor: Intel (0x8086) 03:22:34 INFO - Device ID: 0x0a2e 03:22:34 INFO - Revision ID: 0x0009 03:22:34 INFO - Displays: 03:22:34 INFO - Display: 03:22:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:22:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:22:34 INFO - Display Serial Number: 1600x1200 60 03:22:34 INFO - Main Display: Yes 03:22:34 INFO - Mirror: Off 03:22:34 INFO - Online: Yes 03:22:34 INFO - Rotation: Supported 03:22:34 INFO - Return code: 0 03:22:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:22:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:22:34 INFO - Python 2.7.3 03:22:34 INFO - Return code: 0 03:22:34 INFO - grabbing minidump binary from tooltool 03:22:34 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:22:34 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 0x10eaced30>, '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 0x10e934db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eac6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:22:34 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:22:34 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:22:34 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:22:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:22:34 INFO - Return code: 0 03:22:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:22:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:22:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:22:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:22:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:22:34 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/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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:22:34 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/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0382 --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:22:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0swuVamk4i/Render', 03:22:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:34 INFO - 'HOME': '/Users/cltbld', 03:22:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:34 INFO - 'LOGNAME': 'cltbld', 03:22:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:22:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:22:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:34 INFO - 'MOZ_NO_REMOTE': '1', 03:22:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:22:34 INFO - 'NO_EM_RESTART': '1', 03:22:34 INFO - 'PAGER': '/bin/cat', 03:22:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:34 INFO - 'PWD': '/builds/slave/test', 03:22:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:22:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:34 INFO - 'SHELL': '/bin/bash', 03:22:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLPy4gnyzN/Listeners', 03:22:34 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 03:22:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:34 INFO - 'USER': 'cltbld', 03:22:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:34 INFO - 'XPC_FLAGS': '0x0', 03:22:34 INFO - 'XPC_SERVICE_NAME': '0', 03:22:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:34 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/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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:22:34 INFO - mozversion application_buildid: 20170112004017 03:22:34 INFO - mozversion application_changeset: 1abb4b193740653f1161d86f38dbb79287e7d69b 03:22:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:22:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:22:34 INFO - mozversion application_name: Firefox 03:22:34 INFO - mozversion application_remotingname: firefox-dev 03:22:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:22:34 INFO - mozversion application_vendor: Mozilla 03:22:34 INFO - mozversion application_version: 52.0a2 03:22:34 INFO - mozversion platform_buildid: 20170112004017 03:22:34 INFO - mozversion platform_changeset: 1abb4b193740653f1161d86f38dbb79287e7d69b 03:22:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:22:34 INFO - mozversion platform_version: 52.0a2 03:22:34 INFO - using testdate: 1484220154 03:22:34 INFO - actual date: 1484220154 03:22:34 INFO - starting webserver on 'localhost:49390' 03:22:34 INFO - SUITE-START | Running 2 tests 03:22:34 INFO - TEST-START | dromaeo_css 03:22:34 INFO - operating with platform_type : mac_ 03:22:34 INFO - Initialising browser for dromaeo_css test... 03:22:34 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpzqKYw1/profile http://localhost:49390/getInfo.html) 03:22:37 INFO - PROCESS | 1934 | __metrics Screen width/height:1600/1200 03:22:37 INFO - PROCESS | 1934 | colorDepth:24 03:22:37 INFO - PROCESS | 1934 | Browser inner width/height: 1024/689 03:22:37 INFO - PROCESS | 1934 | __metrics 03:22:37 INFO - PROCESS | 1934 | 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:22:37 INFO - PROCESS | 1934 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:22:38 INFO - TEST-INFO | 1934: exit 0 03:22:38 INFO - Browser initialized. 03:22:38 INFO - Running cycle 1/1 for dromaeo_css test... 03:22:38 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpzqKYw1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:22:50 INFO - PROCESS | 1937 | RSS: Main: 221818880 03:22:50 INFO - PROCESS | 1937 | 03:25:54 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49390/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49390/tests/dromaeo/cssquery-ext.html) 03:25:55 INFO - PROCESS | 1937 | RSS: Main: 358793216 03:25:55 INFO - PROCESS | 1937 | 03:28:58 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49390/tests/dromaeo/cssquery-ext.html (next: http://localhost:49390/tests/dromaeo/cssquery-jquery.html) 03:28:59 INFO - PROCESS | 1937 | RSS: Main: 350306304 03:28:59 INFO - PROCESS | 1937 | 03:32:01 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49390/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49390/tests/dromaeo/cssquery-mootools.html) 03:32:02 INFO - PROCESS | 1937 | RSS: Main: 323153920 03:32:02 INFO - PROCESS | 1937 | 03:35:04 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49390/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49390/tests/dromaeo/cssquery-prototype.html) 03:35:04 INFO - PROCESS | 1937 | RSS: Main: 327733248 03:35:04 INFO - PROCESS | 1937 | 03:38:08 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49390/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49390/tests/dromaeo/cssquery-yui.html) 03:38:09 INFO - PROCESS | 1937 | RSS: Main: 308961280 03:38:09 INFO - PROCESS | 1937 | 03:41:11 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49390/tests/dromaeo/cssquery-yui.html (next: http://localhost:49390/tests/dromaeo/cssquery-dojo.html) 03:41:12 INFO - PROCESS | 1937 | RSS: Main: 367575040 03:41:12 INFO - PROCESS | 1937 | 03:41:12 INFO - PROCESS | 1937 | __start_tp_report 03:41:12 INFO - PROCESS | 1937 | _x_x_mozilla_page_load 03:41:12 INFO - PROCESS | 1937 | _x_x_mozilla_page_load_details 03:41:12 INFO - PROCESS | 1937 | |i|pagename|runs| 03:41:12 INFO - PROCESS | 1937 | |0;dojo.html;2776.6390369252;3098.4887493563565;3165.1137681449204;3263.7878537895035;3353.446681297586;5538.695371754552;5592.105263157896;5613.326400831896;5618.438156184379;5653.830385088449;4528.366028755969;5535.169724541321;5637.390244509113;5643.097104463287;5679.062954612495;5101.846944591659;5111.4121875984365;5124.641275110744;5205.193195054772;5207.713575753332;5072.26452164436;5090.007448547534;5090.821821236257;5172.793088276464;5226.5818734501145;5120.820771272987;5125.077486052509;5137.666051706646;5179.844604661847;5180.507851754096;5125.077486052528;5159.74201289934;5173.637845350827;5184.377874655065;5230.398504172031;4940.7035577865445;5081.79672813087;5097.2608971699265;5111.718855462948;5124.948750512484;5487.42382049883;5507.118860982224;5511.5039646432015;5517.586181036439;5546.667199967975;6037.78867739627;6055.122007308915;6077.36187700294;6128.203333566652;6137.232845894263;6176.258848938156;6177.413145751147;6177.907331390033;6188.226471691039;6189.195404597419;5580.832575022756;5634.943650563483;5638.915416268759;5659.943400565941;5701.2018317435595;6254.186955695731;6286.057091436322;6308.463780578702;6312.116303717483;6330.335314791881;5993.101034844808;5998.340182579913;6005.849853753603;6020.608660437058;6022.812173133401;5987.550933679904;5991.161237426763;6058.091286307089;6151.9692401538605;6158.199434073542;5923.17075609415;5995.700214989321;5997.670128142994;6001.759929602855;6010.579259451883;6074.54440916933;6106.114613381035;6109.959382938842;6132.233470816148;6153.446189842935;4844.031193761191;4848.975755121272;4853.053654537332;4873.415190177201;4882.194437917704;3812.599677033927;4328.632066274339;4348.608625223745;4360.978195109004;4368.4102646143;5559.16612508127;5614.63504872182;5683.829485115453;5703.315602127688;5730.025895597759;6163.322034576193;6191.133241346215;6193.597416167935;6193.6903154842075;6204.937950620526;5568.860778480571;5655.434456554394;5656.519195868397;5674.375818659944;5682.801101961411;5254.159334506537;5255.868603284948;5296.020236256305;5301.284326615858;5302.522772950453;6306.148669929685;6315.336889626523;6322.494200464043;6323.20959880015;6342.175517182736;5983.252093488314;6169.660668663267;6197.96901015492;6206.131141640084;6207.472364849038;5194.662346947436;6123.785667501616;6217.067439884054;6220.409061139185;6314.55798094129;6061.575689701678;6074.3014553325165;6096.055111457731;6105.389461053859;6107.541934354852;5984.162217289496;6185.072239164161;6189.752409903554;6207.81376558704;6209.099680546386;5716.571257155647;5810.128480727924;5925.644461332334;5963.91054134197;5970.432808883149;5842.7662893485485;5846.473817356458;5886.735096920735;5940.524758019262;5960.433758793081;5779.219805326227;5781.277340332458;5785.132230165509;5791.565632577489;5804.448577385142;5749.655020698771;5896.380880007709;5934.732937017759;5945;5963.70181490915;4790.778351520479;4816.325714400057;5731.083026715705;5778.27771528559;5813.186153938536 03:41:12 INFO - PROCESS | 1937 | |1;ext.html;477.54394553201695;488.9804407823687;500.64954531827726;512.7590032684639;518.6913786297151;30639.927602533917;32335.51496727551;39845.40231896516;41175.970600734996;41249.58750412495;42809.35785963213;43199.568004319946;43233;43236.56763432365;43255.35116973248;9120.908790912106;9294.488803115859;9295.95352023239;9308.767280817967;9319.95340023299;8448.704295349664;8675.002374726924;8679.869801952977;9161.541922903862;9301.06989301068;8553.914460855374;8614.655413783441;11258.943705281463;11536.423178841067;11549.769004619902;11429.942850285695;11465.197436179471;11512.424378781112;11541.422928853524;11544.653660390202;11272.830907536394;11293.887061129448;11336.716582085432;11341.716457088556;11341.922517360837;39846.800765995984;40080;40096.39855402171;40144.79927600372;40223.59776402228;3212.662670419619;3275.3776782411264;3460.653934606544;3559.715222782171;3651.7808931463933;29902.252443688863;30056.398872022437;30076;30494;30766.846165769253;3205.7114859662743;3248.902532924016;3252.886148984774;3261.1031966209243;3272.7872688275424;495353;499969;500085.49957250344;500314;506464.46767766296;33806.661933380354;62128.06807897885;62499.6875015622;62502.68748656318;62552;30130.54804177939;34907.60369585188;35617.82191089028;35694.82152589221;35741.82129089338;12252.509899603916;12271.631851044483;14410.927945360207;14588.41646334156;14595.75936045448;12277.93861030707;12353.876461235452;12361.62915112548;12364.072694547946;12375.566855160027;7335.926640733631;7764.84470310602;7772.45592808498;7843.215678432225;7893.526388416713;3090.7063828936216;3126.5935428394164;3127.358891427252;3128.045945986496;3143.858526366319;870.856308709056;919.4069824962861;943.8678584998106;963.9277054220821;972.0813830929845;28776.136715898137;29179;29997.550036749468;30052.69947300543;30712.38575228483;969.2294625772528;989.3816364772017;1028.5525796278644;1036.3056751976194;1135.7160709822545;15141.394344226328;15508.84491155074;15567.766483502757;15673.137977411352;15844.76232856508;188441;190296.04851975653;190474.14288785678;190507;190795;58914.705426472596;59329.110063349086;59498;59516;59606;1207.746373261625;1232.5069972011268;1259.6724851538665;1279.3411393132544;1353.2218974089938;29791.85104074466;30519.54220686647;30664.233394165323;30748.69251307548;31283.687163128077;49646.25530617116;50218.24672629987;50240;50252;50373.74813125911;509677;513074;513272;514524;514594;26877;27523.174304770888;27701.58447623247;27718.86140569284;28042;46469.3029604563;47125.35061272839;47174.764126180526;47374;47660;38598;38622.841314760604;39435;39630.81107566778;40787.59212407838;37145.25709485849;38447.615523844404;38573.61426385812;38983.02542436462;39473.60526394814;36823.63176368202;38629;38725.419118713806;38735.41896871493;38908.832735018;30815.691843081284;34377.31245375029;35092.596296147865;35151.82424087863;35280.82359588288;25503.23490295294;25594.976200952497;25684.486310274064;26075.087371941892;26192.083277085974 03:41:12 INFO - PROCESS | 1937 | |2;jquery.html;356.85904067893165;373.2590807247615;374.88003838771584;389.97075219358544;406.11061774713374;15061.548153555397;15082.622934426645;17114.914425427854;20237.291694790685;20983.580328393462;11914.927656510912;15330.233488325595;19298.807011929875;19901;20168.092435840386;5077.441481437039;5322.175062865262;5327.946720532784;5328.573714102862;5329.733513324338;5148.845534633967;5261.079311120558;5296;5312.07038768214;5312.521873031426;5307.495787900143;5311.920321195185;5313.9734301328435;5314.3091398118195;5319.867003324909;5068.74656267187;5261.894762104775;5301.151815709487;5325.121354976424;5337;5183.637145399824;5191.792328306863;5194.11700010999;5195.402528709171;5212.827113899342;10911.781764364669;12902.161359511601;13083.542076027294;13313.667158321023;13420.664483387896;2278.6354183330586;2375.6080246759266;2706.986465067682;2710.2004908552026;2722.6460560127257;5550.666959982374;5590.86022849428;5611.915821262684;7893.763187104396;10075.013965365933;1571.2222449887379;1612.7662338311202;1787.785465744106;1836.5500452389251;1894.3464504745841;85136;99348.50325748397;128277;133320;134415;10165.39007659528;10449.529771160316;11530.443390142224;11747;11840.526378944745;14548.927255363868;14629.487967921072;14733.852661473247;14760;14768.932349471082;8361.790955226168;8404.07555168931;8455.74632761018;8532.658693652184;8571.442856214326;8330.541820199947;8350.164983501723;8410.36922230835;8464.322854171774;8480.159146685806;4014.397840323916;4194.790260486963;4515.164694531522;4529.5243999380245;4649.256119020941;1711.2384988680167;1804.2512357371543;1889.7448844405828;1901.6006638606048;1916.2430839818194;820.971266005687;868.1925808997693;880.700561808988;888.8577827547562;891.7057371067662;19454.817214298993;20931.47671308199;20961.26635567748;21165.57668846645;21224.25715099964;905.2712566383992;920.3511524375299;927.596495524449;936.9344145909857;971.9659811906549;9621.845378554619;9839.803203936024;10987.780244395068;11015.449227538591;11015.83476247857;610414;612641.9367903221;621807;622214;622773;110354;113004.43497782458;118107.2283915742;118999.40500297616;120733.39633301953;886.9467831930104;909.9363044586815;914.0539541574572;918.4076270805426;945.1304799584399;17453.73819392685;18061.819381806014;18504.444866654023;18587.070646467353;18694.158762855433;44138;53104.73447632737;53372.73313633407;54882.62793430196;56323.71838140947;62931;75359.62320188365;78485.60757196178;79681;80710.385792285;7633.580153091411;7763.262490063435;7942.682292708227;8051.1546287641295;8139.267465927858;9740.80518389614;9990.400575965465;12072.577459788865;12104.213226140273;12198.207116537396;10607.257491975797;10779.303020179355;10841.620543280947;10843.566257349617;10932.453377330943;10364.326318789255;10425.843612345972;10501;10529.210309226686;10543.57825686964;10394.740131496774;10922.890771092505;10993.560257589927;11169.218154729091;11282.492287847232;7822.843543128992;7876.330511906552;7933.246341597539;8103.594820259082;8233.217844304781;8062.072861620989;9751.51242437861;10512.106470950055;10840.566377344818;10941.07000904932 03:41:12 INFO - PROCESS | 1937 | |3;mootools.html;1322.2727499875077;1453.7601295786196;1499.452699764586;1656.8508834204927;1855.7030875060002;7489.475736698433;7901.881471777928;8162.959185204073;8408.495490270589;8432.789180270496;8257.13300103488;8349.707760228379;8365.163483651631;8371;8379.91620083799;2305.723313202414;2336.696229490164;2341.321016905104;2343.8828058597087;2346.894389752457;2312.6993490846253;2355;2375.8336916415856;2376.0614557249964;2376.3346263046374;2324.0471406723245;2328.9534209315802;2352.5059737455153;2354.3996280948336;2368.7039120109985;2305.101010605865;2312.144506532585;2330.5804955108074;2334.0197117210814;2338.1816364272368;2330.5804955108074;2349.036894873111;2350.8119350452093;2354.7174339079247;2355.3405046586813;8270.710525131592;8310.70912518059;8349.791255218608;8378.958105209496;8380.8742868857;5070.08738427083;5270.578353731692;5281.471852814726;5302.1781623848365;5311.389190243133;2939.971010146453;3533.3993221152464;3543.964560354389;3589.6051434342203;3593.766405183681;1068.9679309620647;1101.3777215873006;1554.6735185611035;1730.9567260818455;1745.6246906914948;10927;10993.945030274877;11038.558457661684;11077.279976801563;11099;4394.143142087326;4607.9078418431445;4748.477667456577;4802.231642937113;4852.587530059914;8635.395522313504;8673.522956237468;8708.738737837875;8736.519491427905;8768.956155219183;5096.311997880312;5110.591152707774;5113.386393632788;5151.896962060738;5180.093483640348;3941.3693808990997;4214.557471465513;4722.456917454537;4768.237082066853;4775.283707443911;3691.298653255873;3707.2029513654693;3721.832517536717;3774.5659249186147;3780.9621903781162;1157.803173460497;1160.9651710448532;1164.3246916788412;1175.641429364035;1180.5867946218928;796.4982061301343;893.2987604730386;897.9057198994142;904.5929331800585;909.6816114359921;5422.75597598109;5445.972770136124;5521.613487055948;5761.510271626875;5803.65178089316;982.2878413150409;1006.3257617396362;1009.2128140050772;1025.8358662613946;1052.7578656908802;3594.5866225383893;3624.4744512045604;3665.8086122010454;3760.52993375828;3801.429785532137;9841.114299713061;10323;10331.94834025825;10337.9483102584;10363.63727269542;10289.639862604923;10305.53625086858;10314.226433017553;10326.967303269614;10336.586536538454;7521.360684341892;7813.101493328227;7972.003499562555;7976.84046319082;7984.720534781255;5269.868253293698;5431.4296998816135;5572.721363931868;5686.374498805211;5786.132080188006;9119.270458363448;9125.77185570366;9421.152096311365;9504.192143667866;9514.38156519824;9115.316351273814;9343.906560934576;9488.715338539854;9520.428774273565;9556.187724043533;7665.8466830661955;7685.423593230642;7811.96094019549;7892.723754668559;7909.129995700583;7784.494007889469;7829.412794040356;7876.803079923047;7936.325412340016;7936.682532698627;6066.514678825595;7103.147622285359;7133.464990125865;7165.534240274365;7171.784846454615;6980;7021.68402421882;7037.753678621224;7093.680784364819;7100.360967512948;6535.542512024303;6643.169603799525;6648.90026649591;6648.933510664831;6656.5340426169705;4712.246040633482;4753.096911586787;4766.833160839354;4777.641676874317;4842.83050093245;4602.309653551994;4735.21868891629;4752.714837109785;4807.711537307633;4961.461946796505 03:41:12 INFO - PROCESS | 1937 | |4;prototype.html;290.1252723040034;299.3354752449561;302.7184718212063;317.6331337305413;320.44402960862914;5013.273075404064;5162.303089082978;5180.79330273076;5249.947500524993;5251.553617942474;4326.956730432695;4673.17273049805;5178.9900969311;5183.714895680736;5217.399999000116;3365.459078395814;3528.7177025838;3751.8499260029566;4032.3145065338963;4038.5961403859524;3778.981105094471;4035.717499775017;4056.391541268804;4065.0122160212222;4074.6740260779065;3618.945715814265;3802.144517483558;3907.6483116519507;3985.4818873546396;4004.9399259010993;3473.392156372625;4003.259397011562;4004.7997600120025;4009.5990400959963;4012.558618551957;3535.2399234164523;3546.882731939431;3548.769329993542;3549.4498352755363;3550.715942724576;4281.614654681093;4897.755112244409;4926.3842019747535;5022.6233032522705;5149.639525233235;4491.932621010687;4496.437945256843;4516.458025036984;4542.432195975503;4553.5218802025975;5388.461153884619;5389.407165211824;5399.811006614789;5409.26974858393;5427.348718153847;3173.694650311344;3717.8326975286177;3809.6190380961957;3831.731778775487;3872.3610604250266;5008.721031930463;5380.542653874386;5649.604527683064;5655.830325090254;5661.688607126606;3172.698593633601;3726.9627303727157;3770.245950809849;3888.4556162137324;3898.8050597470015;3921.470601468822;3929.8821035368983;3956.9208615827524;3957.3668213085766;3972.900677483086;4516.8644940651175;4525.683202175816;4558.9772051139535;4564.041551274203;4568.291914753218;4767.951050768826;4919.65562410635;5139.9720055988955;5176.5858731301405;5363.919541206885;1238.0405185980758;1279.309173046563;1280.4302085572017;1284.2726532813351;1307.1960744142284;810.1817164663614;819.0253598218102;892.433304851424;894.5706061090713;905.6196397513061;3904.492415985904;4630.884227894329;4839.443009388097;4843.95156048435;4855.732934688555;4264.360345948071;4437.156940181355;5256.185291279858;5277.973610131925;5298.860744939857;4226.767527786002;4458.888527786831;4847;4856.41722993236;4860.708357498562;2759.9926027;2786.832790032564;2790.990486849465;2792.8743206555755;2796.860156992142;5016.409984863809;5388.084025715638;5483.2049352844415;5530.2303262956275;5553.194786755897;5186.625544230782;5364.249005139283;5441.646292990943;5470.72646367688;5788.855278618068;5862.237909071793;5891.705414729332;5912.231409916683;5914.585978981516;5970.611910225822;5681.772729090955;5715.428457154418;5724.6778682094755;5736.282964629421;5739.512141468022;4239.279322515118;4644.58198762113;5069.750257132336;5312.910853274992;5664.207011018379;4568.977155114203;5512.5314348280845;5705.686187259741;5852.385499522659;5885.73514191871;3073.9077827665205;3762.981185094057;3821;3834.7123965702126;3853.845846166122;5418.512333889968;5638.605297629127;5667.603267771381;5699.5155411790465;5722.3991480895575;5345;5420.78032442713;5476.945230547643;5501.9174712380145;5555.3611334697025;5425.6744595322825;5434.23387399089;5443.4284400138995;5455.318085239345;5489.698066606375;4930.038642464685;5161.587073034073;5298.397089978851;5340.49265319794;5398.597624057257;4211.810468528863;4633.443986721615;4956.18373852139;5170.97414512925;5300.231466437422;4429.955700442954;4655.580997710223;5235.63350565472;5310.867228319323;5369.95285919242 03:41:12 INFO - PROCESS | 1937 | |5;yui.html;698.1238545625237;712.6472396163899;756.1757684124303;765.35710003597;796.4106561144753;13936.581902542926;14539.563813085611;14560.27198640069;14571.92714036431;14579.562613121598;12661.30362830044;12764.80852787209;12770.872291277085;12771.978241740662;12790.744185116291;666.473485946102;684.336193891924;686.7047169717019;825.4263287015511;838.6854929401475;807.6042739057879;829.1045670675655;833.1418638802044;839.9496030238205;841.3479553346144;892.1658249536672;893.3121496447732;896.4576431259093;896.7802888292377;899.7750562359411;702.9753958611424;884.0938038510546;888.9644414223424;889.9777505562348;890.3856339125982;538.0020062783558;538.6983289357972;538.9676619402849;539.6303532080537;631.6589041917359;13616.114952528054;14104.647383815487;14127.505537306144;14138;14151.433942642281;12985.610431687064;13026.218426894417;13206.471741130343;13293.73412531744;13309.600711978559;11944.581939632071;12064.733203013646;12259.264444133381;12343.321117338543;12572.245665260114;4204.369344598304;4243.61807437329;4282.314829627276;4474.7091439056685;4576.1762882681105;61701.691491542704;91849.08150918472;97896.0210397894;98375.52436713455;100259.48053895988;10402.167826573856;10509.684709458728;10532.473376331152;10540.472976351151;10748.94625526883;20024.098915548835;20205.989700514914;20253.29113481021;20268.89865550663;20289.289874854603;7257.419406447577;7555.055618047744;7589.8861517077285;7759.340456061298;7766.223377662189;4095.3447448408706;4130.091379896418;4141.627253547195;4238.957610423856;4345.739255644671;2845.487812193825;3295.6210035845706;3319.468884978392;3322.5182348559324;3323.3851737428654;609.0924522461589;625.9405356491126;627.0218459203656;628.2617923939353;633.7306644675995;551.4705882352906;552.1772558887309;555.3391464157639;601.6239850093691;602.6052935327367;8015.398845086642;8286.171382861665;8710.433821801562;8796.824063518821;8861.468311901306;548.3775914337256;548.867467262913;599.8500374906273;604.7218279591438;639.0829160155192;3935.74417662851;4206.642435392964;4258.297380932174;4288.529915715043;4302.741835489818;104450;111348.32977505344;114015;118192.1355898311;123150;64267.67866160639;81715.34594206284;87930.56034719785;93397.53301233586;107968.4601576987;76173.61913190398;78942.81585776103;80091.59954200192;81159.782603261;83559;8093.959530202547;8102.554359510284;8382.706605268786;8405.201505856934;8755.693550725695;40419.595804041586;50221.24668129908;51915.96168076693;53068.4693153079;53303;68716.312836873;75054.62472687602;76896.2310376889;77554.22445775695;77992.61003694945;11755;12112.15214934946;12140.514379424723;12259.448324825582;12403.813942790684;13071.281079540717;14210.502632407815;14229.928850355682;14244.07413518118;14291.785623215868;10499.737506562396;12494.31281279502;13079.673008174872;13185.142965707582;13193.538226162038;12650.493980240688;13126.737465250451;13341;13510.662233444242;13923.164610123426;12213;12718.554850580185;12740.426680799572;12782.552610658582;13347.399367028702;10244.59021639156;10394.22043346737;10405.9479702601;10465.633702820365;11047.668569942914;10722.19583531254;11095.611653592085;11280.32318060919;11342.432878356214;11383.601573944872 03:41:12 INFO - PROCESS | 1937 | __end_tp_report 03:41:12 INFO - PROCESS | 1937 | __start_cc_report 03:41:12 INFO - PROCESS | 1937 | _x_x_mozilla_cycle_collect,1450 03:41:12 INFO - PROCESS | 1937 | __end_cc_report 03:41:12 INFO - PROCESS | 1937 | __startTimestamp1484221272229__endTimestamp 03:41:12 INFO - PROCESS | 1937 | 03:41:12 INFO - PROCESS | 1937 | ------- Summary: start ------- 03:41:12 INFO - PROCESS | 1937 | Number of tests: 6 03:41:12 INFO - PROCESS | 1937 | 03:41:12 INFO - PROCESS | 1937 | [#0] dojo.html Cycles:165 Average:5611.42 Median:5779.22 stddev:666.06 (11.5%) stddev-sans-first:629.89 03:41:12 INFO - PROCESS | 1937 | Values: 2776.6 3098.5 3165.1 3263.8 3353.4 5538.7 5592.1 5613.3 5618.4 5653.8 4528.4 5535.2 5637.4 5643.1 5679.1 5101.8 5111.4 5124.6 5205.2 5207.7 5072.3 5090.0 5090.8 5172.8 5226.6 5120.8 5125.1 5137.7 5179.8 5180.5 5125.1 5159.7 5173.6 5184.4 5230.4 4940.7 5081.8 5097.3 5111.7 5124.9 5487.4 5507.1 5511.5 5517.6 5546.7 6037.8 6055.1 6077.4 6128.2 6137.2 6176.3 6177.4 6177.9 6188.2 6189.2 5580.8 5634.9 5638.9 5659.9 5701.2 6254.2 6286.1 6308.5 6312.1 6330.3 5993.1 5998.3 6005.8 6020.6 6022.8 5987.6 5991.2 6058.1 6152.0 6158.2 5923.2 5995.7 5997.7 6001.8 6010.6 6074.5 6106.1 6110.0 6132.2 6153.4 4844.0 4849.0 4853.1 4873.4 4882.2 3812.6 4328.6 4348.6 4361.0 4368.4 5559.2 5614.6 5683.8 5703.3 5730.0 6163.3 6191.1 6193.6 6193.7 6204.9 5568.9 5655.4 5656.5 5674.4 5682.8 5254.2 5255.9 5296.0 5301.3 5302.5 6306.1 6315.3 6322.5 6323.2 6342.2 5983.3 6169.7 6198.0 6206.1 6207.5 5194.7 6123.8 6217.1 6220.4 6314.6 6061.6 6074.3 6096.1 6105.4 6107.5 5984.2 6185.1 6189.8 6207.8 6209.1 5716.6 5810.1 5925.6 5963.9 5970.4 5842.8 5846.5 5886.7 5940.5 5960.4 5779.2 5781.3 5785.1 5791.6 5804.4 5749.7 5896.4 5934.7 5945.0 5963.7 4790.8 4816.3 5731.1 5778.3 5813.2 03:41:12 INFO - PROCESS | 1937 | 03:41:12 INFO - PROCESS | 1937 | [#1] ext.html Cycles:180 Average:55002.45 Median:35656.32 stddev:114530.33 (321.2%) stddev-sans-first:114778.45 03:41:12 INFO - PROCESS | 1937 | Values: 477.5 489.0 500.6 512.8 518.7 30639.9 32335.5 39845.4 41176.0 41249.6 42809.4 43199.6 43233.0 43236.6 43255.4 9120.9 9294.5 9296.0 9308.8 9320.0 8448.7 8675.0 8679.9 9161.5 9301.1 8553.9 8614.7 11258.9 11536.4 11549.8 11429.9 11465.2 11512.4 11541.4 11544.7 11272.8 11293.9 11336.7 11341.7 11341.9 39846.8 40080.0 40096.4 40144.8 40223.6 3212.7 3275.4 3460.7 3559.7 3651.8 29902.3 30056.4 30076.0 30494.0 30766.8 3205.7 3248.9 3252.9 3261.1 3272.8 495353.0 499969.0 500085.5 500314.0 506464.5 33806.7 62128.1 62499.7 62502.7 62552.0 30130.5 34907.6 35617.8 35694.8 35741.8 12252.5 12271.6 14410.9 14588.4 14595.8 12277.9 12353.9 12361.6 12364.1 12375.6 7335.9 7764.8 7772.5 7843.2 7893.5 3090.7 3126.6 3127.4 3128.0 3143.9 870.9 919.4 943.9 963.9 972.1 28776.1 29179.0 29997.6 30052.7 30712.4 969.2 989.4 1028.6 1036.3 1135.7 15141.4 15508.8 15567.8 15673.1 15844.8 188441.0 190296.0 190474.1 190507.0 190795.0 58914.7 59329.1 59498.0 59516.0 59606.0 1207.7 1232.5 1259.7 1279.3 1353.2 29791.9 30519.5 30664.2 30748.7 31283.7 49646.3 50218.2 50240.0 50252.0 50373.7 509677.0 513074.0 513272.0 514524.0 514594.0 26877.0 27523.2 27701.6 27718.9 28042.0 46469.3 47125.4 47174.8 47374.0 47660.0 38598.0 38622.8 39435.0 39630.8 40787.6 37145.3 38447.6 38573.6 38983.0 39473.6 36823.6 38629.0 38725.4 38735.4 38908.8 30815.7 34377.3 35092.6 35151.8 35280.8 25503.2 25595.0 25684.5 26075.1 26192.1 03:41:12 INFO - PROCESS | 1937 | 03:41:12 INFO - PROCESS | 1937 | [#2] jquery.html Cycles:180 Average:34286.37 Median:24326.58 stddev:102833.45 (422.7%) stddev-sans-first:103090.37 03:41:12 INFO - PROCESS | 1937 | Values: 356.9 373.3 374.9 390.0 406.1 15061.5 15082.6 17114.9 20237.3 20983.6 11914.9 15330.2 19298.8 19901.0 20168.1 5077.4 5322.2 5327.9 5328.6 5329.7 5148.8 5261.1 5296.0 5312.1 5312.5 5307.5 5311.9 5314.0 5314.3 5319.9 5068.7 5261.9 5301.2 5325.1 5337.0 5183.6 5191.8 5194.1 5195.4 5212.8 10911.8 12902.2 13083.5 13313.7 13420.7 2278.6 2375.6 2707.0 2710.2 2722.6 5550.7 5590.9 5611.9 7893.8 10075.0 1571.2 1612.8 1787.8 1836.6 1894.3 85136.0 99348.5 128277.0 133320.0 134415.0 10165.4 10449.5 11530.4 11747.0 11840.5 14548.9 14629.5 14733.9 14760.0 14768.9 8361.8 8404.1 8455.7 8532.7 8571.4 8330.5 8350.2 8410.4 8464.3 8480.2 4014.4 4194.8 4515.2 4529.5 4649.3 1711.2 1804.3 1889.7 1901.6 1916.2 821.0 868.2 880.7 888.9 891.7 19454.8 20931.5 20961.3 21165.6 21224.3 905.3 920.4 927.6 936.9 972.0 9621.8 9839.8 10987.8 11015.4 11015.8 610414.0 612641.9 621807.0 622214.0 622773.0 110354.0 113004.4 118107.2 118999.4 120733.4 886.9 909.9 914.1 918.4 945.1 17453.7 18061.8 18504.4 18587.1 18694.2 44138.0 53104.7 53372.7 54882.6 56323.7 62931.0 75359.6 78485.6 79681.0 80710.4 7633.6 7763.3 7942.7 8051.2 8139.3 9740.8 9990.4 12072.6 12104.2 12198.2 10607.3 10779.3 10841.6 10843.6 10932.5 10364.3 10425.8 10501.0 10529.2 10543.6 10394.7 10922.9 10993.6 11169.2 11282.5 7822.8 7876.3 7933.2 8103.6 8233.2 8062.1 9751.5 10512.1 10840.6 10941.1 03:41:12 INFO - PROCESS | 1937 | 03:41:12 INFO - PROCESS | 1937 | [#3] mootools.html Cycles:180 Average:5465.96 Median:4847.71 stddev:2980.01 (61.5%) stddev-sans-first:2972.10 03:41:12 INFO - PROCESS | 1937 | Values: 1322.3 1453.8 1499.5 1656.9 1855.7 7489.5 7901.9 8163.0 8408.5 8432.8 8257.1 8349.7 8365.2 8371.0 8379.9 2305.7 2336.7 2341.3 2343.9 2346.9 2312.7 2355.0 2375.8 2376.1 2376.3 2324.0 2329.0 2352.5 2354.4 2368.7 2305.1 2312.1 2330.6 2334.0 2338.2 2330.6 2349.0 2350.8 2354.7 2355.3 8270.7 8310.7 8349.8 8379.0 8380.9 5070.1 5270.6 5281.5 5302.2 5311.4 2940.0 3533.4 3544.0 3589.6 3593.8 1069.0 1101.4 1554.7 1731.0 1745.6 10927.0 10993.9 11038.6 11077.3 11099.0 4394.1 4607.9 4748.5 4802.2 4852.6 8635.4 8673.5 8708.7 8736.5 8769.0 5096.3 5110.6 5113.4 5151.9 5180.1 3941.4 4214.6 4722.5 4768.2 4775.3 3691.3 3707.2 3721.8 3774.6 3781.0 1157.8 1161.0 1164.3 1175.6 1180.6 796.5 893.3 897.9 904.6 909.7 5422.8 5446.0 5521.6 5761.5 5803.7 982.3 1006.3 1009.2 1025.8 1052.8 3594.6 3624.5 3665.8 3760.5 3801.4 9841.1 10323.0 10331.9 10337.9 10363.6 10289.6 10305.5 10314.2 10327.0 10336.6 7521.4 7813.1 7972.0 7976.8 7984.7 5269.9 5431.4 5572.7 5686.4 5786.1 9119.3 9125.8 9421.2 9504.2 9514.4 9115.3 9343.9 9488.7 9520.4 9556.2 7665.8 7685.4 7812.0 7892.7 7909.1 7784.5 7829.4 7876.8 7936.3 7936.7 6066.5 7103.1 7133.5 7165.5 7171.8 6980.0 7021.7 7037.8 7093.7 7100.4 6535.5 6643.2 6648.9 6648.9 6656.5 4712.2 4753.1 4766.8 4777.6 4842.8 4602.3 4735.2 4752.7 4807.7 4961.5 03:41:12 INFO - PROCESS | 1937 | 03:41:12 INFO - PROCESS | 1937 | [#4] prototype.html Cycles:180 Average:4388.74 Median:4856.08 stddev:1337.09 (27.5%) stddev-sans-first:1304.98 03:41:12 INFO - PROCESS | 1937 | Values: 290.1 299.3 302.7 317.6 320.4 5013.3 5162.3 5180.8 5249.9 5251.6 4327.0 4673.2 5179.0 5183.7 5217.4 3365.5 3528.7 3751.8 4032.3 4038.6 3779.0 4035.7 4056.4 4065.0 4074.7 3618.9 3802.1 3907.6 3985.5 4004.9 3473.4 4003.3 4004.8 4009.6 4012.6 3535.2 3546.9 3548.8 3549.4 3550.7 4281.6 4897.8 4926.4 5022.6 5149.6 4491.9 4496.4 4516.5 4542.4 4553.5 5388.5 5389.4 5399.8 5409.3 5427.3 3173.7 3717.8 3809.6 3831.7 3872.4 5008.7 5380.5 5649.6 5655.8 5661.7 3172.7 3727.0 3770.2 3888.5 3898.8 3921.5 3929.9 3956.9 3957.4 3972.9 4516.9 4525.7 4559.0 4564.0 4568.3 4768.0 4919.7 5140.0 5176.6 5363.9 1238.0 1279.3 1280.4 1284.3 1307.2 810.2 819.0 892.4 894.6 905.6 3904.5 4630.9 4839.4 4844.0 4855.7 4264.4 4437.2 5256.2 5278.0 5298.9 4226.8 4458.9 4847.0 4856.4 4860.7 2760.0 2786.8 2791.0 2792.9 2796.9 5016.4 5388.1 5483.2 5530.2 5553.2 5186.6 5364.2 5441.6 5470.7 5788.9 5862.2 5891.7 5912.2 5914.6 5970.6 5681.8 5715.4 5724.7 5736.3 5739.5 4239.3 4644.6 5069.8 5312.9 5664.2 4569.0 5512.5 5705.7 5852.4 5885.7 3073.9 3763.0 3821.0 3834.7 3853.8 5418.5 5638.6 5667.6 5699.5 5722.4 5345.0 5420.8 5476.9 5501.9 5555.4 5425.7 5434.2 5443.4 5455.3 5489.7 4930.0 5161.6 5298.4 5340.5 5398.6 4211.8 4633.4 4956.2 5171.0 5300.2 4430.0 4655.6 5235.6 5310.9 5370.0 03:41:12 INFO - PROCESS | 1937 | 03:41:12 INFO - PROCESS | 1937 | [#5] yui.html Cycles:180 Average:20192.00 Median:4250.96 stddev:29823.15 (701.6%) stddev-sans-first:29870.90 03:41:12 INFO - PROCESS | 1937 | Values: 698.1 712.6 756.2 765.4 796.4 13936.6 14539.6 14560.3 14571.9 14579.6 12661.3 12764.8 12770.9 12772.0 12790.7 666.5 684.3 686.7 825.4 838.7 807.6 829.1 833.1 839.9 841.3 892.2 893.3 896.5 896.8 899.8 703.0 884.1 889.0 890.0 890.4 538.0 538.7 539.0 539.6 631.7 13616.1 14104.6 14127.5 14138.0 14151.4 12985.6 13026.2 13206.5 13293.7 13309.6 11944.6 12064.7 12259.3 12343.3 12572.2 4204.4 4243.6 4282.3 4474.7 4576.2 61701.7 91849.1 97896.0 98375.5 100259.5 10402.2 10509.7 10532.5 10540.5 10748.9 20024.1 20206.0 20253.3 20268.9 20289.3 7257.4 7555.1 7589.9 7759.3 7766.2 4095.3 4130.1 4141.6 4239.0 4345.7 2845.5 3295.6 3319.5 3322.5 3323.4 609.1 625.9 627.0 628.3 633.7 551.5 552.2 555.3 601.6 602.6 8015.4 8286.2 8710.4 8796.8 8861.5 548.4 548.9 599.9 604.7 639.1 3935.7 4206.6 4258.3 4288.5 4302.7 104450.0 111348.3 114015.0 118192.1 123150.0 64267.7 81715.3 87930.6 93397.5 107968.5 76173.6 78942.8 80091.6 81159.8 83559.0 8094.0 8102.6 8382.7 8405.2 8755.7 40419.6 50221.2 51916.0 53068.5 53303.0 68716.3 75054.6 76896.2 77554.2 77992.6 11755.0 12112.2 12140.5 12259.4 12403.8 13071.3 14210.5 14229.9 14244.1 14291.8 10499.7 12494.3 13079.7 13185.1 13193.5 12650.5 13126.7 13341.0 13510.7 13923.2 12213.0 12718.6 12740.4 12782.6 13347.4 10244.6 10394.2 10405.9 10465.6 11047.7 10722.2 11095.6 11280.3 11342.4 11383.6 03:41:12 INFO - PROCESS | 1937 | -------- Summary: end -------- 03:41:12 INFO - PROCESS | 1937 | 03:41:12 INFO - TEST-INFO | 1937: exit 0 03:41:12 INFO - TEST-OK | dromaeo_css | took 1117938ms 03:41:12 INFO - TEST-START | kraken 03:41:12 INFO - operating with platform_type : mac_ 03:41:12 INFO - Initialising browser for kraken test... 03:41:12 INFO - TEST-INFO | started process 1965 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpKkjewB/profile http://localhost:49390/getInfo.html) 03:41:15 INFO - PROCESS | 1965 | __metrics Screen width/height:1600/1200 03:41:15 INFO - PROCESS | 1965 | colorDepth:24 03:41:15 INFO - PROCESS | 1965 | Browser inner width/height: 1024/689 03:41:15 INFO - PROCESS | 1965 | __metrics 03:41:15 INFO - PROCESS | 1965 | 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:41:15 INFO - PROCESS | 1965 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:41:16 INFO - TEST-INFO | 1965: exit 0 03:41:16 INFO - Browser initialized. 03:41:16 INFO - Running cycle 1/1 for kraken test... 03:41:16 INFO - TEST-INFO | started process 1967 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpKkjewB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:41:28 INFO - PROCESS | 1967 | RSS: Main: 221917184 03:41:28 INFO - PROCESS | 1967 | 03:41:31 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49390/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49390/tests/kraken/driver.html?testName=audio-beat-detection) 03:41:32 INFO - PROCESS | 1967 | RSS: Main: 271269888 03:41:32 INFO - PROCESS | 1967 | 03:41:34 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49390/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49390/tests/kraken/driver.html?testName=audio-dft) 03:41:35 INFO - PROCESS | 1967 | RSS: Main: 283521024 03:41:35 INFO - PROCESS | 1967 | 03:41:38 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49390/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49390/tests/kraken/driver.html?testName=audio-fft) 03:41:39 INFO - PROCESS | 1967 | RSS: Main: 380952576 03:41:39 INFO - PROCESS | 1967 | 03:41:41 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49390/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49390/tests/kraken/driver.html?testName=audio-oscillator) 03:41:41 INFO - PROCESS | 1967 | RSS: Main: 282656768 03:41:41 INFO - PROCESS | 1967 | 03:41:43 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49390/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49390/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:41:44 INFO - PROCESS | 1967 | RSS: Main: 283430912 03:41:44 INFO - PROCESS | 1967 | 03:41:47 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49390/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49390/tests/kraken/driver.html?testName=imaging-darkroom) 03:41:47 INFO - PROCESS | 1967 | RSS: Main: 324968448 03:41:47 INFO - PROCESS | 1967 | 03:41:50 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49390/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49390/tests/kraken/driver.html?testName=imaging-desaturate) 03:41:51 INFO - PROCESS | 1967 | RSS: Main: 325824512 03:41:51 INFO - PROCESS | 1967 | 03:41:54 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49390/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49390/tests/kraken/driver.html?testName=json-parse-financial) 03:41:54 INFO - PROCESS | 1967 | RSS: Main: 323985408 03:41:54 INFO - PROCESS | 1967 | 03:41:56 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49390/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49390/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:41:56 INFO - PROCESS | 1967 | RSS: Main: 297242624 03:41:56 INFO - PROCESS | 1967 | 03:41:58 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49390/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49390/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:41:59 INFO - PROCESS | 1967 | RSS: Main: 300867584 03:41:59 INFO - PROCESS | 1967 | 03:42:01 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49390/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49390/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:42:02 INFO - PROCESS | 1967 | RSS: Main: 301768704 03:42:02 INFO - PROCESS | 1967 | 03:42:04 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49390/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49390/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:42:05 INFO - PROCESS | 1967 | RSS: Main: 321208320 03:42:05 INFO - PROCESS | 1967 | 03:42:08 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49390/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49390/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:42:09 INFO - PROCESS | 1967 | RSS: Main: 314384384 03:42:09 INFO - PROCESS | 1967 | 03:42:10 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49390/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49390/tests/kraken/driver.html?testName=ai-astar) 03:42:11 INFO - PROCESS | 1967 | RSS: Main: 358576128 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | __start_tp_report 03:42:11 INFO - PROCESS | 1967 | _x_x_mozilla_page_load 03:42:11 INFO - PROCESS | 1967 | _x_x_mozilla_page_load_details 03:42:11 INFO - PROCESS | 1967 | |i|pagename|runs| 03:42:11 INFO - PROCESS | 1967 | |0;ai-astar;116;116;117;113;114;115;115;116;115;115 03:42:11 INFO - PROCESS | 1967 | |1;audio-beat-detection;142;109;111;176;141;141;109;108;140;109 03:42:11 INFO - PROCESS | 1967 | |2;audio-dft;185;181;185;184;184;182;182;183;184;184 03:42:11 INFO - PROCESS | 1967 | |3;audio-fft;81;58;59;88;59;59;62;58;81;57 03:42:11 INFO - PROCESS | 1967 | |4;audio-oscillator;88;69;94;72;67;69;90;68;69;117 03:42:11 INFO - PROCESS | 1967 | |5;imaging-gaussian-blur;88;84;87;84;87;84;87;85;88;129 03:42:11 INFO - PROCESS | 1967 | |6;imaging-darkroom;96;95;108;98;95;95;96;96;95;95 03:42:11 INFO - PROCESS | 1967 | |7;imaging-desaturate;85;81;82;84;83;84;82;84;84;84 03:42:11 INFO - PROCESS | 1967 | |8;json-parse-financial;56;54;55;56;58;59;58;58;57;57 03:42:11 INFO - PROCESS | 1967 | |9;json-stringify-tinderbox;64;68;81;74;72;72;69;70;69;77 03:42:11 INFO - PROCESS | 1967 | |10;stanford-crypto-aes;84;85;84;83;81;84;81;84;84;85 03:42:11 INFO - PROCESS | 1967 | |11;stanford-crypto-ccm;141;139;138;138;140;142;143;143;187;139 03:42:11 INFO - PROCESS | 1967 | |12;stanford-crypto-pbkdf2;166;160;165;164;165;163;165;163;164;164 03:42:11 INFO - PROCESS | 1967 | |13;stanford-crypto-sha256-iterative;67;68;65;67;66;66;66;67;66;68 03:42:11 INFO - PROCESS | 1967 | __end_tp_report 03:42:11 INFO - PROCESS | 1967 | __start_cc_report 03:42:11 INFO - PROCESS | 1967 | _x_x_mozilla_cycle_collect,1931 03:42:11 INFO - PROCESS | 1967 | __end_cc_report 03:42:11 INFO - PROCESS | 1967 | __startTimestamp1484221331141__endTimestamp 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | ------- Summary: start ------- 03:42:11 INFO - PROCESS | 1967 | Number of tests: 14 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | [#0] ai-astar Cycles:10 Average:115.20 Median:115.50 stddev:1.14 (1.0%) stddev-sans-first:1.17 03:42:11 INFO - PROCESS | 1967 | Values: 116.0 116.0 117.0 113.0 114.0 115.0 115.0 116.0 115.0 115.0 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | [#1] audio-beat-detection Cycles:10 Average:128.60 Median:140.50 stddev:22.97 (16.4%) stddev-sans-first:23.85 03:42:11 INFO - PROCESS | 1967 | Values: 142.0 109.0 111.0 176.0 141.0 141.0 109.0 108.0 140.0 109.0 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.35 (0.7%) stddev-sans-first:1.30 03:42:11 INFO - PROCESS | 1967 | Values: 185.0 181.0 185.0 184.0 184.0 182.0 182.0 183.0 184.0 184.0 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | [#3] audio-fft Cycles:10 Average:66.20 Median:60.50 stddev:12.04 (19.9%) stddev-sans-first:11.52 03:42:11 INFO - PROCESS | 1967 | Values: 81.0 58.0 59.0 88.0 59.0 59.0 62.0 58.0 81.0 57.0 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | [#4] audio-oscillator Cycles:10 Average:80.30 Median:70.50 stddev:16.56 (23.5%) stddev-sans-first:17.33 03:42:11 INFO - PROCESS | 1967 | Values: 88.0 69.0 94.0 72.0 67.0 69.0 90.0 68.0 69.0 117.0 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:87.00 stddev:13.70 (15.7%) stddev-sans-first:14.50 03:42:11 INFO - PROCESS | 1967 | Values: 88.0 84.0 87.0 84.0 87.0 84.0 87.0 85.0 88.0 129.0 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | [#6] imaging-darkroom Cycles:10 Average:96.90 Median:95.50 stddev:4.01 (4.2%) stddev-sans-first:4.24 03:42:11 INFO - PROCESS | 1967 | Values: 96.0 95.0 108.0 98.0 95.0 95.0 96.0 96.0 95.0 95.0 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | [#7] imaging-desaturate Cycles:10 Average:83.30 Median:84.00 stddev:1.25 (1.5%) stddev-sans-first:1.17 03:42:11 INFO - PROCESS | 1967 | Values: 85.0 81.0 82.0 84.0 83.0 84.0 82.0 84.0 84.0 84.0 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | [#8] json-parse-financial Cycles:10 Average:56.80 Median:57.50 stddev:1.55 (2.7%) stddev-sans-first:1.62 03:42:11 INFO - PROCESS | 1967 | Values: 56.0 54.0 55.0 56.0 58.0 59.0 58.0 58.0 57.0 57.0 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:72.00 stddev:4.84 (6.7%) stddev-sans-first:4.28 03:42:11 INFO - PROCESS | 1967 | Values: 64.0 68.0 81.0 74.0 72.0 72.0 69.0 70.0 69.0 77.0 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.00 stddev:1.43 (1.7%) stddev-sans-first:1.51 03:42:11 INFO - PROCESS | 1967 | Values: 84.0 85.0 84.0 83.0 81.0 84.0 81.0 84.0 84.0 85.0 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | [#11] stanford-crypto-ccm Cycles:10 Average:145.00 Median:141.50 stddev:14.88 (10.5%) stddev-sans-first:15.71 03:42:11 INFO - PROCESS | 1967 | Values: 141.0 139.0 138.0 138.0 140.0 142.0 143.0 143.0 187.0 139.0 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.90 Median:164.50 stddev:1.66 (1.0%) stddev-sans-first:1.58 03:42:11 INFO - PROCESS | 1967 | Values: 166.0 160.0 165.0 164.0 165.0 163.0 165.0 163.0 164.0 164.0 03:42:11 INFO - PROCESS | 1967 | 03:42:11 INFO - PROCESS | 1967 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:0.97 (1.4%) stddev-sans-first:1.01 03:42:11 INFO - PROCESS | 1967 | Values: 67.0 68.0 65.0 67.0 66.0 66.0 66.0 67.0 66.0 68.0 03:42:11 INFO - PROCESS | 1967 | -------- Summary: end -------- 03:42:11 INFO - PROCESS | 1967 | 03:42:12 INFO - TEST-INFO | 1967: exit 0 03:42:12 INFO - TEST-OK | kraken | took 59330ms 03:42:12 INFO - SUITE-END | took 1177s 03:42:12 INFO - Completed test suite (00:19:37) 03:42:12 INFO - javascript benchmark 03:42:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2776.6390369252, 3098.4887493563565, 3165.1137681449204, 3263.7878537895035, 3353.446681297586, 5538.695371754552, 5592.105263157896, 5613.326400831896, 5618.438156184379, 5653.830385088449, 4528.366028755969, 5535.169724541321, 5637.390244509113, 5643.097104463287, 5679.062954612495, 5101.846944591659, 5111.4121875984365, 5124.641275110744, 5205.193195054772, 5207.713575753332, 5072.26452164436, 5090.007448547534, 5090.821821236257, 5172.793088276464, 5226.5818734501145, 5120.820771272987, 5125.077486052509, 5137.666051706646, 5179.844604661847, 5180.507851754096, 5125.077486052528, 5159.74201289934, 5173.637845350827, 5184.377874655065, 5230.398504172031, 4940.7035577865445, 5081.79672813087, 5097.2608971699265, 5111.718855462948, 5124.948750512484, 5487.42382049883, 5507.118860982224, 5511.5039646432015, 5517.586181036439, 5546.667199967975, 6037.78867739627, 6055.122007308915, 6077.36187700294, 6128.203333566652, 6137.232845894263, 6176.258848938156, 6177.413145751147, 6177.907331390033, 6188.226471691039, 6189.195404597419, 5580.832575022756, 5634.943650563483, 5638.915416268759, 5659.943400565941, 5701.2018317435595, 6254.186955695731, 6286.057091436322, 6308.463780578702, 6312.116303717483, 6330.335314791881, 5993.101034844808, 5998.340182579913, 6005.849853753603, 6020.608660437058, 6022.812173133401, 5987.550933679904, 5991.161237426763, 6058.091286307089, 6151.9692401538605, 6158.199434073542, 5923.17075609415, 5995.700214989321, 5997.670128142994, 6001.759929602855, 6010.579259451883, 6074.54440916933, 6106.114613381035, 6109.959382938842, 6132.233470816148, 6153.446189842935, 4844.031193761191, 4848.975755121272, 4853.053654537332, 4873.415190177201, 4882.194437917704, 3812.599677033927, 4328.632066274339, 4348.608625223745, 4360.978195109004, 4368.4102646143, 5559.16612508127, 5614.63504872182, 5683.829485115453, 5703.315602127688, 5730.025895597759, 6163.322034576193, 6191.133241346215, 6193.597416167935, 6193.6903154842075, 6204.937950620526, 5568.860778480571, 5655.434456554394, 5656.519195868397, 5674.375818659944, 5682.801101961411, 5254.159334506537, 5255.868603284948, 5296.020236256305, 5301.284326615858, 5302.522772950453, 6306.148669929685, 6315.336889626523, 6322.494200464043, 6323.20959880015, 6342.175517182736, 5983.252093488314, 6169.660668663267, 6197.96901015492, 6206.131141640084, 6207.472364849038, 5194.662346947436, 6123.785667501616, 6217.067439884054, 6220.409061139185, 6314.55798094129, 6061.575689701678, 6074.3014553325165, 6096.055111457731, 6105.389461053859, 6107.541934354852, 5984.162217289496, 6185.072239164161, 6189.752409903554, 6207.81376558704, 6209.099680546386, 5716.571257155647, 5810.128480727924, 5925.644461332334, 5963.91054134197, 5970.432808883149, 5842.7662893485485, 5846.473817356458, 5886.735096920735, 5940.524758019262, 5960.433758793081, 5779.219805326227, 5781.277340332458, 5785.132230165509, 5791.565632577489, 5804.448577385142, 5749.655020698771, 5896.380880007709, 5934.732937017759, 5945.0, 5963.70181490915, 4790.778351520479, 4816.325714400057, 5731.083026715705, 5778.27771528559, 5813.186153938536], "value": 5566.154281466773, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.54394553201695, 488.9804407823687, 500.64954531827726, 512.7590032684639, 518.6913786297151, 30639.927602533917, 32335.51496727551, 39845.40231896516, 41175.970600734996, 41249.58750412495, 42809.35785963213, 43199.568004319946, 43233.0, 43236.56763432365, 43255.35116973248, 9120.908790912106, 9294.488803115859, 9295.95352023239, 9308.767280817967, 9319.95340023299, 8448.704295349664, 8675.002374726924, 8679.869801952977, 9161.541922903862, 9301.06989301068, 8553.914460855374, 8614.655413783441, 11258.943705281463, 11536.423178841067, 11549.769004619902, 11429.942850285695, 11465.197436179471, 11512.424378781112, 11541.422928853524, 11544.653660390202, 11272.830907536394, 11293.887061129448, 11336.716582085432, 11341.716457088556, 11341.922517360837, 39846.800765995984, 40080.0, 40096.39855402171, 40144.79927600372, 40223.59776402228, 3212.662670419619, 3275.3776782411264, 3460.653934606544, 3559.715222782171, 3651.7808931463933, 29902.252443688863, 30056.398872022437, 30076.0, 30494.0, 30766.846165769253, 3205.7114859662743, 3248.902532924016, 3252.886148984774, 3261.1031966209243, 3272.7872688275424, 495353.0, 499969.0, 500085.49957250344, 500314.0, 506464.46767766296, 33806.661933380354, 62128.06807897885, 62499.6875015622, 62502.68748656318, 62552.0, 30130.54804177939, 34907.60369585188, 35617.82191089028, 35694.82152589221, 35741.82129089338, 12252.509899603916, 12271.631851044483, 14410.927945360207, 14588.41646334156, 14595.75936045448, 12277.93861030707, 12353.876461235452, 12361.62915112548, 12364.072694547946, 12375.566855160027, 7335.926640733631, 7764.84470310602, 7772.45592808498, 7843.215678432225, 7893.526388416713, 3090.7063828936216, 3126.5935428394164, 3127.358891427252, 3128.045945986496, 3143.858526366319, 870.856308709056, 919.4069824962861, 943.8678584998106, 963.9277054220821, 972.0813830929845, 28776.136715898137, 29179.0, 29997.550036749468, 30052.69947300543, 30712.38575228483, 969.2294625772528, 989.3816364772017, 1028.5525796278644, 1036.3056751976194, 1135.7160709822545, 15141.394344226328, 15508.84491155074, 15567.766483502757, 15673.137977411352, 15844.76232856508, 188441.0, 190296.04851975653, 190474.14288785678, 190507.0, 190795.0, 58914.705426472596, 59329.110063349086, 59498.0, 59516.0, 59606.0, 1207.746373261625, 1232.5069972011268, 1259.6724851538665, 1279.3411393132544, 1353.2218974089938, 29791.85104074466, 30519.54220686647, 30664.233394165323, 30748.69251307548, 31283.687163128077, 49646.25530617116, 50218.24672629987, 50240.0, 50252.0, 50373.74813125911, 509677.0, 513074.0, 513272.0, 514524.0, 514594.0, 26877.0, 27523.174304770888, 27701.58447623247, 27718.86140569284, 28042.0, 46469.3029604563, 47125.35061272839, 47174.764126180526, 47374.0, 47660.0, 38598.0, 38622.841314760604, 39435.0, 39630.81107566778, 40787.59212407838, 37145.25709485849, 38447.615523844404, 38573.61426385812, 38983.02542436462, 39473.60526394814, 36823.63176368202, 38629.0, 38725.419118713806, 38735.41896871493, 38908.832735018, 30815.691843081284, 34377.31245375029, 35092.596296147865, 35151.82424087863, 35280.82359588288, 25503.23490295294, 25594.976200952497, 25684.486310274064, 26075.087371941892, 26192.083277085974], "value": 18011.46517032314, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.85904067893165, 373.2590807247615, 374.88003838771584, 389.97075219358544, 406.11061774713374, 15061.548153555397, 15082.622934426645, 17114.914425427854, 20237.291694790685, 20983.580328393462, 11914.927656510912, 15330.233488325595, 19298.807011929875, 19901.0, 20168.092435840386, 5077.441481437039, 5322.175062865262, 5327.946720532784, 5328.573714102862, 5329.733513324338, 5148.845534633967, 5261.079311120558, 5296.0, 5312.07038768214, 5312.521873031426, 5307.495787900143, 5311.920321195185, 5313.9734301328435, 5314.3091398118195, 5319.867003324909, 5068.74656267187, 5261.894762104775, 5301.151815709487, 5325.121354976424, 5337.0, 5183.637145399824, 5191.792328306863, 5194.11700010999, 5195.402528709171, 5212.827113899342, 10911.781764364669, 12902.161359511601, 13083.542076027294, 13313.667158321023, 13420.664483387896, 2278.6354183330586, 2375.6080246759266, 2706.986465067682, 2710.2004908552026, 2722.6460560127257, 5550.666959982374, 5590.86022849428, 5611.915821262684, 7893.763187104396, 10075.013965365933, 1571.2222449887379, 1612.7662338311202, 1787.785465744106, 1836.5500452389251, 1894.3464504745841, 85136.0, 99348.50325748397, 128277.0, 133320.0, 134415.0, 10165.39007659528, 10449.529771160316, 11530.443390142224, 11747.0, 11840.526378944745, 14548.927255363868, 14629.487967921072, 14733.852661473247, 14760.0, 14768.932349471082, 8361.790955226168, 8404.07555168931, 8455.74632761018, 8532.658693652184, 8571.442856214326, 8330.541820199947, 8350.164983501723, 8410.36922230835, 8464.322854171774, 8480.159146685806, 4014.397840323916, 4194.790260486963, 4515.164694531522, 4529.5243999380245, 4649.256119020941, 1711.2384988680167, 1804.2512357371543, 1889.7448844405828, 1901.6006638606048, 1916.2430839818194, 820.971266005687, 868.1925808997693, 880.700561808988, 888.8577827547562, 891.7057371067662, 19454.817214298993, 20931.47671308199, 20961.26635567748, 21165.57668846645, 21224.25715099964, 905.2712566383992, 920.3511524375299, 927.596495524449, 936.9344145909857, 971.9659811906549, 9621.845378554619, 9839.803203936024, 10987.780244395068, 11015.449227538591, 11015.83476247857, 610414.0, 612641.9367903221, 621807.0, 622214.0, 622773.0, 110354.0, 113004.43497782458, 118107.2283915742, 118999.40500297616, 120733.39633301953, 886.9467831930104, 909.9363044586815, 914.0539541574572, 918.4076270805426, 945.1304799584399, 17453.73819392685, 18061.819381806014, 18504.444866654023, 18587.070646467353, 18694.158762855433, 44138.0, 53104.73447632737, 53372.73313633407, 54882.62793430196, 56323.71838140947, 62931.0, 75359.62320188365, 78485.60757196178, 79681.0, 80710.385792285, 7633.580153091411, 7763.262490063435, 7942.682292708227, 8051.1546287641295, 8139.267465927858, 9740.80518389614, 9990.400575965465, 12072.577459788865, 12104.213226140273, 12198.207116537396, 10607.257491975797, 10779.303020179355, 10841.620543280947, 10843.566257349617, 10932.453377330943, 10364.326318789255, 10425.843612345972, 10501.0, 10529.210309226686, 10543.57825686964, 10394.740131496774, 10922.890771092505, 10993.560257589927, 11169.218154729091, 11282.492287847232, 7822.843543128992, 7876.330511906552, 7933.246341597539, 8103.594820259082, 8233.217844304781, 8062.072861620989, 9751.51242437861, 10512.106470950055, 10840.566377344818, 10941.07000904932], "value": 8899.097343148911, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1322.2727499875077, 1453.7601295786196, 1499.452699764586, 1656.8508834204927, 1855.7030875060002, 7489.475736698433, 7901.881471777928, 8162.959185204073, 8408.495490270589, 8432.789180270496, 8257.13300103488, 8349.707760228379, 8365.163483651631, 8371.0, 8379.91620083799, 2305.723313202414, 2336.696229490164, 2341.321016905104, 2343.8828058597087, 2346.894389752457, 2312.6993490846253, 2355.0, 2375.8336916415856, 2376.0614557249964, 2376.3346263046374, 2324.0471406723245, 2328.9534209315802, 2352.5059737455153, 2354.3996280948336, 2368.7039120109985, 2305.101010605865, 2312.144506532585, 2330.5804955108074, 2334.0197117210814, 2338.1816364272368, 2330.5804955108074, 2349.036894873111, 2350.8119350452093, 2354.7174339079247, 2355.3405046586813, 8270.710525131592, 8310.70912518059, 8349.791255218608, 8378.958105209496, 8380.8742868857, 5070.08738427083, 5270.578353731692, 5281.471852814726, 5302.1781623848365, 5311.389190243133, 2939.971010146453, 3533.3993221152464, 3543.964560354389, 3589.6051434342203, 3593.766405183681, 1068.9679309620647, 1101.3777215873006, 1554.6735185611035, 1730.9567260818455, 1745.6246906914948, 10927.0, 10993.945030274877, 11038.558457661684, 11077.279976801563, 11099.0, 4394.143142087326, 4607.9078418431445, 4748.477667456577, 4802.231642937113, 4852.587530059914, 8635.395522313504, 8673.522956237468, 8708.738737837875, 8736.519491427905, 8768.956155219183, 5096.311997880312, 5110.591152707774, 5113.386393632788, 5151.896962060738, 5180.093483640348, 3941.3693808990997, 4214.557471465513, 4722.456917454537, 4768.237082066853, 4775.283707443911, 3691.298653255873, 3707.2029513654693, 3721.832517536717, 3774.5659249186147, 3780.9621903781162, 1157.803173460497, 1160.9651710448532, 1164.3246916788412, 1175.641429364035, 1180.5867946218928, 796.4982061301343, 893.2987604730386, 897.9057198994142, 904.5929331800585, 909.6816114359921, 5422.75597598109, 5445.972770136124, 5521.613487055948, 5761.510271626875, 5803.65178089316, 982.2878413150409, 1006.3257617396362, 1009.2128140050772, 1025.8358662613946, 1052.7578656908802, 3594.5866225383893, 3624.4744512045604, 3665.8086122010454, 3760.52993375828, 3801.429785532137, 9841.114299713061, 10323.0, 10331.94834025825, 10337.9483102584, 10363.63727269542, 10289.639862604923, 10305.53625086858, 10314.226433017553, 10326.967303269614, 10336.586536538454, 7521.360684341892, 7813.101493328227, 7972.003499562555, 7976.84046319082, 7984.720534781255, 5269.868253293698, 5431.4296998816135, 5572.721363931868, 5686.374498805211, 5786.132080188006, 9119.270458363448, 9125.77185570366, 9421.152096311365, 9504.192143667866, 9514.38156519824, 9115.316351273814, 9343.906560934576, 9488.715338539854, 9520.428774273565, 9556.187724043533, 7665.8466830661955, 7685.423593230642, 7811.96094019549, 7892.723754668559, 7909.129995700583, 7784.494007889469, 7829.412794040356, 7876.803079923047, 7936.325412340016, 7936.682532698627, 6066.514678825595, 7103.147622285359, 7133.464990125865, 7165.534240274365, 7171.784846454615, 6980.0, 7021.68402421882, 7037.753678621224, 7093.680784364819, 7100.360967512948, 6535.542512024303, 6643.169603799525, 6648.90026649591, 6648.933510664831, 6656.5340426169705, 4712.246040633482, 4753.096911586787, 4766.833160839354, 4777.641676874317, 4842.83050093245, 4602.309653551994, 4735.21868891629, 4752.714837109785, 4807.711537307633, 4961.461946796505], "value": 4463.427141208803, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.1252723040034, 299.3354752449561, 302.7184718212063, 317.6331337305413, 320.44402960862914, 5013.273075404064, 5162.303089082978, 5180.79330273076, 5249.947500524993, 5251.553617942474, 4326.956730432695, 4673.17273049805, 5178.9900969311, 5183.714895680736, 5217.399999000116, 3365.459078395814, 3528.7177025838, 3751.8499260029566, 4032.3145065338963, 4038.5961403859524, 3778.981105094471, 4035.717499775017, 4056.391541268804, 4065.0122160212222, 4074.6740260779065, 3618.945715814265, 3802.144517483558, 3907.6483116519507, 3985.4818873546396, 4004.9399259010993, 3473.392156372625, 4003.259397011562, 4004.7997600120025, 4009.5990400959963, 4012.558618551957, 3535.2399234164523, 3546.882731939431, 3548.769329993542, 3549.4498352755363, 3550.715942724576, 4281.614654681093, 4897.755112244409, 4926.3842019747535, 5022.6233032522705, 5149.639525233235, 4491.932621010687, 4496.437945256843, 4516.458025036984, 4542.432195975503, 4553.5218802025975, 5388.461153884619, 5389.407165211824, 5399.811006614789, 5409.26974858393, 5427.348718153847, 3173.694650311344, 3717.8326975286177, 3809.6190380961957, 3831.731778775487, 3872.3610604250266, 5008.721031930463, 5380.542653874386, 5649.604527683064, 5655.830325090254, 5661.688607126606, 3172.698593633601, 3726.9627303727157, 3770.245950809849, 3888.4556162137324, 3898.8050597470015, 3921.470601468822, 3929.8821035368983, 3956.9208615827524, 3957.3668213085766, 3972.900677483086, 4516.8644940651175, 4525.683202175816, 4558.9772051139535, 4564.041551274203, 4568.291914753218, 4767.951050768826, 4919.65562410635, 5139.9720055988955, 5176.5858731301405, 5363.919541206885, 1238.0405185980758, 1279.309173046563, 1280.4302085572017, 1284.2726532813351, 1307.1960744142284, 810.1817164663614, 819.0253598218102, 892.433304851424, 894.5706061090713, 905.6196397513061, 3904.492415985904, 4630.884227894329, 4839.443009388097, 4843.95156048435, 4855.732934688555, 4264.360345948071, 4437.156940181355, 5256.185291279858, 5277.973610131925, 5298.860744939857, 4226.767527786002, 4458.888527786831, 4847.0, 4856.41722993236, 4860.708357498562, 2759.9926027, 2786.832790032564, 2790.990486849465, 2792.8743206555755, 2796.860156992142, 5016.409984863809, 5388.084025715638, 5483.2049352844415, 5530.2303262956275, 5553.194786755897, 5186.625544230782, 5364.249005139283, 5441.646292990943, 5470.72646367688, 5788.855278618068, 5862.237909071793, 5891.705414729332, 5912.231409916683, 5914.585978981516, 5970.611910225822, 5681.772729090955, 5715.428457154418, 5724.6778682094755, 5736.282964629421, 5739.512141468022, 4239.279322515118, 4644.58198762113, 5069.750257132336, 5312.910853274992, 5664.207011018379, 4568.977155114203, 5512.5314348280845, 5705.686187259741, 5852.385499522659, 5885.73514191871, 3073.9077827665205, 3762.981185094057, 3821.0, 3834.7123965702126, 3853.845846166122, 5418.512333889968, 5638.605297629127, 5667.603267771381, 5699.5155411790465, 5722.3991480895575, 5345.0, 5420.78032442713, 5476.945230547643, 5501.9174712380145, 5555.3611334697025, 5425.6744595322825, 5434.23387399089, 5443.4284400138995, 5455.318085239345, 5489.698066606375, 4930.038642464685, 5161.587073034073, 5298.397089978851, 5340.49265319794, 5398.597624057257, 4211.810468528863, 4633.443986721615, 4956.18373852139, 5170.97414512925, 5300.231466437422, 4429.955700442954, 4655.580997710223, 5235.63350565472, 5310.867228319323, 5369.95285919242], "value": 3965.538388362999, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.1238545625237, 712.6472396163899, 756.1757684124303, 765.35710003597, 796.4106561144753, 13936.581902542926, 14539.563813085611, 14560.27198640069, 14571.92714036431, 14579.562613121598, 12661.30362830044, 12764.80852787209, 12770.872291277085, 12771.978241740662, 12790.744185116291, 666.473485946102, 684.336193891924, 686.7047169717019, 825.4263287015511, 838.6854929401475, 807.6042739057879, 829.1045670675655, 833.1418638802044, 839.9496030238205, 841.3479553346144, 892.1658249536672, 893.3121496447732, 896.4576431259093, 896.7802888292377, 899.7750562359411, 702.9753958611424, 884.0938038510546, 888.9644414223424, 889.9777505562348, 890.3856339125982, 538.0020062783558, 538.6983289357972, 538.9676619402849, 539.6303532080537, 631.6589041917359, 13616.114952528054, 14104.647383815487, 14127.505537306144, 14138.0, 14151.433942642281, 12985.610431687064, 13026.218426894417, 13206.471741130343, 13293.73412531744, 13309.600711978559, 11944.581939632071, 12064.733203013646, 12259.264444133381, 12343.321117338543, 12572.245665260114, 4204.369344598304, 4243.61807437329, 4282.314829627276, 4474.7091439056685, 4576.1762882681105, 61701.691491542704, 91849.08150918472, 97896.0210397894, 98375.52436713455, 100259.48053895988, 10402.167826573856, 10509.684709458728, 10532.473376331152, 10540.472976351151, 10748.94625526883, 20024.098915548835, 20205.989700514914, 20253.29113481021, 20268.89865550663, 20289.289874854603, 7257.419406447577, 7555.055618047744, 7589.8861517077285, 7759.340456061298, 7766.223377662189, 4095.3447448408706, 4130.091379896418, 4141.627253547195, 4238.957610423856, 4345.739255644671, 2845.487812193825, 3295.6210035845706, 3319.468884978392, 3322.5182348559324, 3323.3851737428654, 609.0924522461589, 625.9405356491126, 627.0218459203656, 628.2617923939353, 633.7306644675995, 551.4705882352906, 552.1772558887309, 555.3391464157639, 601.6239850093691, 602.6052935327367, 8015.398845086642, 8286.171382861665, 8710.433821801562, 8796.824063518821, 8861.468311901306, 548.3775914337256, 548.867467262913, 599.8500374906273, 604.7218279591438, 639.0829160155192, 3935.74417662851, 4206.642435392964, 4258.297380932174, 4288.529915715043, 4302.741835489818, 104450.0, 111348.32977505344, 114015.0, 118192.1355898311, 123150.0, 64267.67866160639, 81715.34594206284, 87930.56034719785, 93397.53301233586, 107968.4601576987, 76173.61913190398, 78942.81585776103, 80091.59954200192, 81159.782603261, 83559.0, 8093.959530202547, 8102.554359510284, 8382.706605268786, 8405.201505856934, 8755.693550725695, 40419.595804041586, 50221.24668129908, 51915.96168076693, 53068.4693153079, 53303.0, 68716.312836873, 75054.62472687602, 76896.2310376889, 77554.22445775695, 77992.61003694945, 11755.0, 12112.15214934946, 12140.514379424723, 12259.448324825582, 12403.813942790684, 13071.281079540717, 14210.502632407815, 14229.928850355682, 14244.07413518118, 14291.785623215868, 10499.737506562396, 12494.31281279502, 13079.673008174872, 13185.142965707582, 13193.538226162038, 12650.493980240688, 13126.737465250451, 13341.0, 13510.662233444242, 13923.164610123426, 12213.0, 12718.554850580185, 12740.426680799572, 12782.552610658582, 13347.399367028702, 10244.59021639156, 10394.22043346737, 10405.9479702601, 10465.633702820365, 11047.668569942914, 10722.19583531254, 11095.611653592085, 11280.32318060919, 11342.432878356214, 11383.601573944872], "value": 7145.588498933449, "unit": "score"}], "name": "dromaeo_css", "value": 6951.583673111688, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 113.0, 114.0, 115.0, 115.0, 116.0, 115.0, 115.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 109.0, 111.0, 176.0, 141.0, 141.0, 109.0, 108.0, 140.0, 109.0], "value": 128.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 181.0, 185.0, 184.0, 184.0, 182.0, 182.0, 183.0, 184.0, 184.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 59.0, 88.0, 59.0, 59.0, 62.0, 58.0, 81.0, 57.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 94.0, 72.0, 67.0, 69.0, 90.0, 68.0, 69.0, 117.0], "value": 80.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 84.0, 87.0, 84.0, 87.0, 85.0, 88.0, 129.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 108.0, 98.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 96.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 82.0, 84.0, 83.0, 84.0, 82.0, 84.0, 84.0, 84.0], "value": 83.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 56.0, 58.0, 59.0, 58.0, 58.0, 57.0, 57.0], "value": 56.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 81.0, 74.0, 72.0, 72.0, 69.0, 70.0, 69.0, 77.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 83.0, 81.0, 84.0, 81.0, 84.0, 84.0, 85.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 138.0, 138.0, 140.0, 142.0, 143.0, 143.0, 187.0, 139.0], "value": 145.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 160.0, 165.0, 164.0, 165.0, 163.0, 165.0, 163.0, 164.0, 164.0], "value": 163.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 65.0, 67.0, 66.0, 66.0, 66.0, 67.0, 66.0, 68.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1431.6, "alertThreshold": 2.0}]} 03:42:12 INFO - Return code: 0 03:42:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:42:12 INFO - # TBPL SUCCESS # 03:42:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:42:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:42:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:42:12 INFO - 2017-01-12 03:42:12.481 screenresolution[1971:11382] starting screenresolution argv=screenresolution get 03:42:12 INFO - 2017-01-12 03:42:12.486 screenresolution[1971:11382] Display 0: 1600x1200x32@60 03:42:12 INFO - 2017-01-12 03:42:12.492 screenresolution[1972:11385] starting screenresolution argv=screenresolution list 03:42:12 INFO - Available Modes on Display 0 03:42:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:42:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:42:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:42:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:42:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:42:12 INFO - Intel Iris: 03:42:12 INFO - Chipset Model: Intel Iris 03:42:12 INFO - Type: GPU 03:42:12 INFO - Bus: Built-In 03:42:12 INFO - VRAM (Dynamic, Max): 1536 MB 03:42:12 INFO - Vendor: Intel (0x8086) 03:42:12 INFO - Device ID: 0x0a2e 03:42:12 INFO - Revision ID: 0x0009 03:42:12 INFO - Displays: 03:42:12 INFO - Display: 03:42:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:42:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:42:12 INFO - Display Serial Number: 1600x1200 60 03:42:12 INFO - Main Display: Yes 03:42:12 INFO - Mirror: Off 03:42:12 INFO - Online: Yes 03:42:12 INFO - Rotation: Supported 03:42:12 INFO - Return code: 0 03:42:12 INFO - Running post-action listener: _resource_record_post_action 03:42:12 INFO - [mozharness: 2017-01-12 11:42:12.734826Z] Finished run-tests step (success) 03:42:12 INFO - Running post-run listener: _resource_record_post_run 03:42:12 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 2351104; Write bytes: 659379712; Read time: 23; Write time: 1933 03:42:12 INFO - TinderboxPrint: CPU usage
53.6% 03:42:12 INFO - TinderboxPrint: I/O read bytes / time
2,351,104 / 23 03:42:12 INFO - TinderboxPrint: I/O write bytes / time
659,379,712 / 1,933 03:42:12 INFO - TinderboxPrint: CPU idle
2,246.5 (46.4%) 03:42:12 INFO - TinderboxPrint: CPU system
967.6 (20.0%) 03:42:12 INFO - TinderboxPrint: CPU user
1,623.7 (33.6%) 03:42:12 INFO - TinderboxPrint: Swap in / out
351,797,248 / 0 03:42:12 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 348180992; Write bytes: 345556480; Read time: 26778; Write time: 749 03:42:12 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1363968; Write bytes: 306536448; Read time: 18; Write time: 1152 03:42:13 INFO - Running post-run listener: _upload_blobber_files 03:42:13 INFO - Blob upload gear active. 03:42:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:42:13 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:42:13 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:42:13 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:42:13 INFO - (blobuploader) - INFO - Open directory for files ... 03:42:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:42:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:42:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:14 INFO - (blobuploader) - INFO - Done attempting. 03:42:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:42:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:42:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:15 INFO - (blobuploader) - INFO - Done attempting. 03:42:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:42:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:42:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:18 INFO - (blobuploader) - INFO - Done attempting. 03:42:18 INFO - (blobuploader) - INFO - Iteration through files over. 03:42:18 INFO - Return code: 0 03:42:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:42:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:42:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3dd4fbea020affdaa29f734e84b09a334cc8561d93287c4d311c934e6d5b598356ce0a23b268bb747fd6ad87c1ac72ea09a15837a6bb74ad90acaf7fcf443409", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cbbd4ac159ae11dd0852f6ffef6479c55a67f2fd01eab33b904138a6df6d09c26ad7e773a257c3210843191a9293774c029b7ff97edeaa54fc6385c3c9aa8ec7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45216bb76cb880038b07f9b9a4422c7f6d2449ef77471fd71dbd3e43a6ed88227f49a6ca05aeed679216b45ca07a32557e6a17ca44efd13cbb08227a63d3b761"} 03:42:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:42:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:42:18 INFO - Contents: 03:42:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3dd4fbea020affdaa29f734e84b09a334cc8561d93287c4d311c934e6d5b598356ce0a23b268bb747fd6ad87c1ac72ea09a15837a6bb74ad90acaf7fcf443409", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cbbd4ac159ae11dd0852f6ffef6479c55a67f2fd01eab33b904138a6df6d09c26ad7e773a257c3210843191a9293774c029b7ff97edeaa54fc6385c3c9aa8ec7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45216bb76cb880038b07f9b9a4422c7f6d2449ef77471fd71dbd3e43a6ed88227f49a6ca05aeed679216b45ca07a32557e6a17ca44efd13cbb08227a63d3b761"} 03:42:18 INFO - Running post-run listener: copy_logs_to_upload_dir 03:42:18 INFO - Copying logs to upload dir... 03:42:18 INFO - mkdir: /builds/slave/test/build/upload/logs 03:42:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.686138 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-12 03:42:18.465909) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 03:42:18.469490) ========= 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.0swuVamk4i/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.PLPy4gnyzN/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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/3dd4fbea020affdaa29f734e84b09a334cc8561d93287c4d311c934e6d5b598356ce0a23b268bb747fd6ad87c1ac72ea09a15837a6bb74ad90acaf7fcf443409", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cbbd4ac159ae11dd0852f6ffef6479c55a67f2fd01eab33b904138a6df6d09c26ad7e773a257c3210843191a9293774c029b7ff97edeaa54fc6385c3c9aa8ec7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45216bb76cb880038b07f9b9a4422c7f6d2449ef77471fd71dbd3e43a6ed88227f49a6ca05aeed679216b45ca07a32557e6a17ca44efd13cbb08227a63d3b761"} build_url:https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011019 build_url: 'https://queue.taskcluster.net/v1/task/W0uaBe6AQ_6kPOZRVkW7zg/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/3dd4fbea020affdaa29f734e84b09a334cc8561d93287c4d311c934e6d5b598356ce0a23b268bb747fd6ad87c1ac72ea09a15837a6bb74ad90acaf7fcf443409", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cbbd4ac159ae11dd0852f6ffef6479c55a67f2fd01eab33b904138a6df6d09c26ad7e773a257c3210843191a9293774c029b7ff97edeaa54fc6385c3c9aa8ec7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45216bb76cb880038b07f9b9a4422c7f6d2449ef77471fd71dbd3e43a6ed88227f49a6ca05aeed679216b45ca07a32557e6a17ca44efd13cbb08227a63d3b761"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 03:42:18.503261) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 03:42:18.503589) ========= 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.0swuVamk4i/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.PLPy4gnyzN/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.005034 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 03:42:18.551950) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 03:42:18.552283) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 03:42:18.552607) ========= ========= Total master_lag: 0.18 =========