builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0180 starttime: 1476675523.17 results: success (0) buildid: 20161016193534 builduid: fe24a6776e714e9b8492c86d61d787dd revision: 013a4088a2307fa31995331c4d18b47fbf75a80b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-16 20:38:43.169028) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-16 20:38:43.169430) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-16 20:38:43.169741) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-16 20:38:43.208980) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 20:38:43.209263) ========= 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.nIGLod8ueD/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.isQEDwjUKk/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-16 20:38:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.15M=0.001s 2016-10-16 20:38:43 (8.15 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.534137 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 20:38:43.766931) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 20:38:43.767257) ========= 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.nIGLod8ueD/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.isQEDwjUKk/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.019164 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 20:38:43.817779) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-16 20:38:43.818270) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 013a4088a2307fa31995331c4d18b47fbf75a80b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 013a4088a2307fa31995331c4d18b47fbf75a80b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nIGLod8ueD/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.isQEDwjUKk/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-16 20:38:43,891 truncating revision to first 12 chars 2016-10-16 20:38:43,891 Setting DEBUG logging. 2016-10-16 20:38:43,891 attempt 1/10 2016-10-16 20:38:43,891 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/013a4088a230?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-16 20:38:44,882 unpacking tar archive at: autoland-013a4088a230/testing/mozharness/ program finished with exit code 0 elapsedTime=1.248517 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-16 20:38:45.089313) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-16 20:38:45.089673) ========= script_repo_revision: 013a4088a2307fa31995331c4d18b47fbf75a80b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-16 20:38:45.090076) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-16 20:38:45.090359) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-16 20:38:45.108840) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-16 20:38:45.109264) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nIGLod8ueD/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.isQEDwjUKk/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 20:38:45 INFO - MultiFileLogger online at 20161016 20:38:45 in /builds/slave/test 20:38:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 20:38:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:38:45 INFO - {'append_to_log': False, 20:38:45 INFO - 'base_work_dir': '/builds/slave/test', 20:38:45 INFO - 'blob_upload_branch': 'Autoland', 20:38:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:38:45 INFO - 'branch': 'Autoland', 20:38:45 INFO - 'buildbot_json_path': 'buildprops.json', 20:38:45 INFO - 'config_files': ('talos/mac_config.py',), 20:38:45 INFO - 'default_actions': ('clobber', 20:38:45 INFO - 'read-buildbot-config', 20:38:45 INFO - 'download-and-extract', 20:38:45 INFO - 'populate-webroot', 20:38:45 INFO - 'create-virtualenv', 20:38:45 INFO - 'install', 20:38:45 INFO - 'run-tests'), 20:38:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:38:45 INFO - 'download_minidump_stackwalk': True, 20:38:45 INFO - 'download_symbols': 'ondemand', 20:38:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:38:45 INFO - 'tooltool.py': '/tools/tooltool.py', 20:38:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:38:45 INFO - '/tools/misc-python/virtualenv.py')}, 20:38:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:38:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:38:45 INFO - 'installer_path': 'installer.exe', 20:38:45 INFO - 'log_level': 'info', 20:38:45 INFO - 'log_name': 'talos', 20:38:45 INFO - 'log_to_console': True, 20:38:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:38:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:38:45 INFO - 'opt_config_files': (), 20:38:45 INFO - 'pip_index': False, 20:38:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:38:45 INFO - 'cmd': ('bash', 20:38:45 INFO - '-c', 20:38:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:38:45 INFO - 'enabled': True, 20:38:45 INFO - 'halt_on_failure': False, 20:38:45 INFO - 'name': 'check_screen_resolution'},), 20:38:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:38:45 INFO - 'cmd': ('bash', 20:38:45 INFO - '-c', 20:38:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:38:45 INFO - 'enabled': True, 20:38:45 INFO - 'halt_on_failure': False, 20:38:45 INFO - 'name': 'check_screen_resolution'},), 20:38:45 INFO - 'run_cmd_checks_enabled': True, 20:38:45 INFO - 'sps_profile': False, 20:38:45 INFO - 'sps_profile_interval': 0, 20:38:45 INFO - 'suite': 'dromaeojs', 20:38:45 INFO - 'system_bits': '32', 20:38:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:38:45 INFO - 'title': 't-yosemite-r7-0180', 20:38:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:38:45 INFO - 'use_talos_json': True, 20:38:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:38:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:38:45 INFO - 'work_dir': 'build'} 20:38:45 INFO - [mozharness: 2016-10-17 03:38:45.313150Z] Running clobber step. 20:38:45 INFO - Running pre-action listener: _resource_record_pre_action 20:38:45 INFO - Running main action method: clobber 20:38:45 INFO - rmtree: /builds/slave/test/build 20:38:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:38:46 INFO - Running post-action listener: _resource_record_post_action 20:38:46 INFO - [mozharness: 2016-10-17 03:38:46.234661Z] Finished clobber step (success) 20:38:46 INFO - [mozharness: 2016-10-17 03:38:46.234779Z] Running read-buildbot-config step. 20:38:46 INFO - Running pre-action listener: _resource_record_pre_action 20:38:46 INFO - Running main action method: read_buildbot_config 20:38:46 INFO - Using buildbot properties: 20:38:46 INFO - { 20:38:46 INFO - "project": "", 20:38:46 INFO - "product": "firefox", 20:38:46 INFO - "script_repo_revision": "production", 20:38:46 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 20:38:46 INFO - "repository": "", 20:38:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 20:38:46 INFO - "buildid": "20161016193534", 20:38:46 INFO - "pgo_build": "False", 20:38:46 INFO - "basedir": "/builds/slave/test", 20:38:46 INFO - "buildnumber": 373, 20:38:46 INFO - "slavename": "t-yosemite-r7-0180", 20:38:46 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:38:46 INFO - "platform": "yosemite_r7", 20:38:46 INFO - "scheduler": "tests-autoland-macosx64-talos", 20:38:46 INFO - "branch": "autoland", 20:38:46 INFO - "repo_path": "integration/autoland", 20:38:46 INFO - "revision": "013a4088a2307fa31995331c4d18b47fbf75a80b", 20:38:46 INFO - "stage_platform": "macosx64", 20:38:46 INFO - "builduid": "fe24a6776e714e9b8492c86d61d787dd", 20:38:46 INFO - "slavebuilddir": "test" 20:38:46 INFO - } 20:38:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 20:38:46 INFO - Running post-action listener: _resource_record_post_action 20:38:46 INFO - [mozharness: 2016-10-17 03:38:46.238126Z] Finished read-buildbot-config step (success) 20:38:46 INFO - [mozharness: 2016-10-17 03:38:46.238229Z] Running download-and-extract step. 20:38:46 INFO - Running pre-action listener: _resource_record_pre_action 20:38:46 INFO - Running main action method: download_and_extract 20:38:46 INFO - mkdir: /builds/slave/test/build/tests 20:38:46 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')]} 20:38:46 INFO - trying https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 20:38:46 INFO - Downloading https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 20:38:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 20:38:47 INFO - Downloaded 1199 bytes. 20:38:47 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 20:38:47 INFO - Using the following test package requirements: 20:38:47 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:38:47 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:38:47 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 20:38:47 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:38:47 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 20:38:47 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:38:47 INFO - u'jsshell-mac.zip'], 20:38:47 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:38:47 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 20:38:47 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:38:47 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:38:47 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 20:38:47 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:38:47 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 20:38:47 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:38:47 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 20:38:47 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:38:47 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 20:38:47 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:38:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:38:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:38:47 INFO - Fetch https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:38:47 INFO - Http code: 200 20:38:47 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 20:38:47 INFO - x-amz-cf-id: c-gkd05er3DefS__QbzctIlFyjf6xMFKysLwiC8xAgwG-3NK6XoyaA== 20:38:47 INFO - x-amz-version-id: NtFyV6dTolRu.JyrOfWkmvt3Xd1RJ2mm 20:38:48 INFO - Expected file size: 16509864 20:38:48 INFO - Obtained file size: 16509864 20:38:55 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:38:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:38:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:38:55 INFO - Fetch https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:38:56 INFO - Http code: 200 20:38:56 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 20:38:56 INFO - x-amz-cf-id: zVBiQnV6bm6_oqGUjiu2CsltNHrgoQJY4Jf0GPiZqI8ZfgMn8rVbbA== 20:38:56 INFO - x-amz-version-id: NtFyV6dTolRu.JyrOfWkmvt3Xd1RJ2mm 20:38:56 INFO - Expected file size: 16509864 20:38:56 INFO - Obtained file size: 16509864 20:39:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 20:39:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:39:02 INFO - Fetch https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 20:39:03 INFO - Http code: 200 20:39:03 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 20:39:03 INFO - x-amz-cf-id: 8TDQ-p7_y84MXiXSLQB5Ttl4S4HuP4GkELIjQmKQ8h4D_q3QsZcxCg== 20:39:03 INFO - x-amz-version-id: 3L_XX.74d17vLm9isOKWqlisdAcOSoSX 20:39:04 INFO - Expected file size: 14409028 20:39:04 INFO - Obtained file size: 14409028 20:39:04 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')]} 20:39:04 INFO - trying https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:39:04 INFO - Downloading https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 20:39:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 20:39:07 INFO - Downloaded 111646077 bytes. 20:39:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:39:07 INFO - mkdir: /builds/slave/test/properties 20:39:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:39:07 INFO - Writing to file /builds/slave/test/properties/build_url 20:39:07 INFO - Contents: 20:39:07 INFO - build_url:https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:39:07 INFO - Running post-action listener: _resource_record_post_action 20:39:07 INFO - Running post-action listener: set_extra_try_arguments 20:39:07 INFO - [mozharness: 2016-10-17 03:39:07.852592Z] Finished download-and-extract step (success) 20:39:07 INFO - [mozharness: 2016-10-17 03:39:07.852733Z] Running populate-webroot step. 20:39:07 INFO - Running pre-action listener: _resource_record_pre_action 20:39:07 INFO - Running main action method: populate_webroot 20:39:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:39:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:39:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:39:07 INFO - '--tppagecycles', 20:39:07 INFO - '7'], 20:39:07 INFO - 'tests': ['tsvgm']}}, 20:39:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:39:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:39:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:39:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:39:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:39:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:39:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:39:07 INFO - 'talos_options': ['--disable-e10s'], 20:39:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:39:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:39:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:39:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:39:07 INFO - 'talos_options': ['--disable-e10s'], 20:39:07 INFO - 'tests': ['damp', 'tps']}, 20:39:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:39:07 INFO - 'tests': ['damp', 'tps']}, 20:39:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:39:07 INFO - 'tests': ['dromaeo_dom']}, 20:39:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:39:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:39:07 INFO - 'tests': ['basic_compositor_video']}, 20:39:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:39:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:39:07 INFO - 'tests': ['a11yr', 20:39:07 INFO - 'ts_paint', 20:39:07 INFO - 'tpaint', 20:39:07 INFO - 'sessionrestore', 20:39:07 INFO - 'sessionrestore_no_auto_restore', 20:39:07 INFO - 'tabpaint']}, 20:39:07 INFO - 'other-e10s': {'tests': ['a11yr', 20:39:07 INFO - 'ts_paint', 20:39:07 INFO - 'tpaint', 20:39:07 INFO - 'sessionrestore', 20:39:07 INFO - 'sessionrestore_no_auto_restore', 20:39:07 INFO - 'tabpaint']}, 20:39:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:39:07 INFO - 'ts_paint', 20:39:07 INFO - 'tpaint', 20:39:07 INFO - 'sessionrestore', 20:39:07 INFO - 'sessionrestore_no_auto_restore', 20:39:07 INFO - 'tabpaint']}, 20:39:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:39:07 INFO - 'ts_paint', 20:39:07 INFO - 'tpaint', 20:39:07 INFO - 'sessionrestore', 20:39:07 INFO - 'sessionrestore_no_auto_restore', 20:39:07 INFO - 'tabpaint']}, 20:39:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:39:07 INFO - 'tests': ['a11yr', 20:39:07 INFO - 'ts_paint', 20:39:07 INFO - 'tpaint', 20:39:07 INFO - 'sessionrestore', 20:39:07 INFO - 'sessionrestore_no_auto_restore', 20:39:07 INFO - 'tabpaint']}, 20:39:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:39:07 INFO - 'tests': ['a11yr', 20:39:07 INFO - 'ts_paint', 20:39:07 INFO - 'tpaint', 20:39:07 INFO - 'sessionrestore', 20:39:07 INFO - 'sessionrestore_no_auto_restore', 20:39:07 INFO - 'tabpaint']}, 20:39:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:39:07 INFO - 'tests': ['tsvgx', 20:39:07 INFO - 'tsvgr_opacity', 20:39:07 INFO - 'tart', 20:39:07 INFO - 'tscrollx', 20:39:07 INFO - 'cart']}, 20:39:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:39:07 INFO - 'tsvgr_opacity', 20:39:07 INFO - 'tart', 20:39:07 INFO - 'tscrollx', 20:39:07 INFO - 'cart']}, 20:39:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:39:07 INFO - 'talos_options': ['--disable-e10s'], 20:39:07 INFO - 'tests': ['tp5o']}, 20:39:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:39:07 INFO - 'tests': ['tp5o']}, 20:39:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:39:07 INFO - 'talos_options': ['--disable-e10s', 20:39:07 INFO - '--xperf_path', 20:39:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:39:07 INFO - 'tests': ['tp5n']}, 20:39:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:39:07 INFO - 'talos_options': ['--xperf_path', 20:39:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:39:07 INFO - 'tests': ['tp5n']}}, 20:39:07 INFO - 'talos.zip': {'path': '', 20:39:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:39:07 INFO - Running post-action listener: _resource_record_post_action 20:39:07 INFO - [mozharness: 2016-10-17 03:39:07.871680Z] Finished populate-webroot step (success) 20:39:07 INFO - [mozharness: 2016-10-17 03:39:07.871786Z] Running create-virtualenv step. 20:39:07 INFO - Running pre-action listener: _resource_record_pre_action 20:39:07 INFO - Running main action method: create_virtualenv 20:39:07 INFO - Creating virtualenv /builds/slave/test/build/venv 20:39:07 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 20:39:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:39:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:39:07 INFO - Using real prefix '/tools/python27' 20:39:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:39:08 INFO - Installing distribute.............................................................................................................................................................................................done. 20:39:11 INFO - Installing pip.................done. 20:39:11 INFO - Return code: 0 20:39:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:39:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:39:11 INFO - Reading from file tmpfile_stdout 20:39:11 INFO - Output received: 20:39:11 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) 20:39:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10523b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fc1d4b1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10504fd30>, '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 0x104e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105047b20>, '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.nIGLod8ueD/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.isQEDwjUKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 20:39:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:39:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:39:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIGLod8ueD/Render', 20:39:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:11 INFO - 'HOME': '/Users/cltbld', 20:39:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:11 INFO - 'LOGNAME': 'cltbld', 20:39:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:11 INFO - 'MOZ_NO_REMOTE': '1', 20:39:11 INFO - 'NO_EM_RESTART': '1', 20:39:11 INFO - 'PAGER': '/bin/cat', 20:39:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:11 INFO - 'PWD': '/builds/slave/test', 20:39:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:11 INFO - 'SHELL': '/bin/bash', 20:39:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isQEDwjUKk/Listeners', 20:39:11 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 20:39:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:11 INFO - 'USER': 'cltbld', 20:39:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:11 INFO - 'XPC_FLAGS': '0x0', 20:39:11 INFO - 'XPC_SERVICE_NAME': '0', 20:39:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:12 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 20:39:12 INFO - Cleaning up... 20:39:12 INFO - Return code: 0 20:39:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:39:12 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')]} 20:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:12 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')]} 20:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:12 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 0x10523b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fc1d4b1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10504fd30>, '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 0x104e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105047b20>, '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.nIGLod8ueD/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.isQEDwjUKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 20:39:12 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 20:39:12 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 20:39:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIGLod8ueD/Render', 20:39:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:12 INFO - 'HOME': '/Users/cltbld', 20:39:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:12 INFO - 'LOGNAME': 'cltbld', 20:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:12 INFO - 'MOZ_NO_REMOTE': '1', 20:39:12 INFO - 'NO_EM_RESTART': '1', 20:39:12 INFO - 'PAGER': '/bin/cat', 20:39:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:12 INFO - 'PWD': '/builds/slave/test', 20:39:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:12 INFO - 'SHELL': '/bin/bash', 20:39:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isQEDwjUKk/Listeners', 20:39:12 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 20:39:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:12 INFO - 'USER': 'cltbld', 20:39:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:12 INFO - 'XPC_FLAGS': '0x0', 20:39:12 INFO - 'XPC_SERVICE_NAME': '0', 20:39:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:12 INFO - Downloading/unpacking psutil>=3.1.1 20:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:39:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:39:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:39:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:39:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:39:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:39:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:39:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:39:16 INFO - Installing collected packages: psutil 20:39:16 INFO - Running setup.py install for psutil 20:39:16 INFO - building 'psutil._psutil_osx' extension 20:39:16 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 20:39:16 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 20:39:16 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 20:39:16 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 20:39:16 INFO - building 'psutil._psutil_posix' extension 20:39:16 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 20:39:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:39:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:39:16 INFO - ^ 20:39:16 INFO - 1 warning generated. 20:39:16 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 20:39:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:39:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:39:16 INFO - Successfully installed psutil 20:39:16 INFO - Cleaning up... 20:39:17 INFO - Return code: 0 20:39:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:39:17 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')]} 20:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:17 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')]} 20:39:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:17 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 0x10523b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fc1d4b1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10504fd30>, '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 0x104e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105047b20>, '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.nIGLod8ueD/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.isQEDwjUKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 20:39:17 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 20:39:17 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 20:39:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIGLod8ueD/Render', 20:39:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:17 INFO - 'HOME': '/Users/cltbld', 20:39:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:17 INFO - 'LOGNAME': 'cltbld', 20:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:17 INFO - 'MOZ_NO_REMOTE': '1', 20:39:17 INFO - 'NO_EM_RESTART': '1', 20:39:17 INFO - 'PAGER': '/bin/cat', 20:39:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:17 INFO - 'PWD': '/builds/slave/test', 20:39:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:17 INFO - 'SHELL': '/bin/bash', 20:39:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isQEDwjUKk/Listeners', 20:39:17 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 20:39:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:17 INFO - 'USER': 'cltbld', 20:39:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:17 INFO - 'XPC_FLAGS': '0x0', 20:39:17 INFO - 'XPC_SERVICE_NAME': '0', 20:39:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:39:17 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 20:39:17 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 20:39:17 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 20:39:17 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 20:39:17 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 20:39:17 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 20:39:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:39:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:39:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:39:19 INFO - Installing collected packages: mozsystemmonitor 20:39:19 INFO - Running setup.py install for mozsystemmonitor 20:39:20 INFO - Successfully installed mozsystemmonitor 20:39:20 INFO - Cleaning up... 20:39:20 INFO - Return code: 0 20:39:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:39:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10523b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fc1d4b1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10504fd30>, '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 0x104e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105047b20>, '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.nIGLod8ueD/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.isQEDwjUKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 20:39:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:39:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:39:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIGLod8ueD/Render', 20:39:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:20 INFO - 'HOME': '/Users/cltbld', 20:39:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:20 INFO - 'LOGNAME': 'cltbld', 20:39:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:20 INFO - 'MOZ_NO_REMOTE': '1', 20:39:20 INFO - 'NO_EM_RESTART': '1', 20:39:20 INFO - 'PAGER': '/bin/cat', 20:39:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:20 INFO - 'PWD': '/builds/slave/test', 20:39:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:20 INFO - 'SHELL': '/bin/bash', 20:39:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isQEDwjUKk/Listeners', 20:39:20 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 20:39:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:20 INFO - 'USER': 'cltbld', 20:39:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:20 INFO - 'XPC_FLAGS': '0x0', 20:39:20 INFO - 'XPC_SERVICE_NAME': '0', 20:39:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:20 INFO - Downloading/unpacking jsonschema==2.5.1 20:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:39:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:39:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:39:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:39:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:39:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:39:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:39:22 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 20:39:22 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 20:39:22 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 20:39:22 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 20:39:22 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 20:39:22 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 20:39:23 INFO - Downloading functools32-3.2.3-2.tar.gz 20:39:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:39:23 INFO - warning: no files found matching '*.txt' 20:39:23 INFO - no previously-included directories found matching 'build' 20:39:23 INFO - no previously-included directories found matching 'dist' 20:39:23 INFO - no previously-included directories found matching '.git*' 20:39:23 INFO - Installing collected packages: jsonschema, functools32 20:39:23 INFO - Running setup.py install for functools32 20:39:23 INFO - warning: no files found matching '*.txt' 20:39:23 INFO - no previously-included directories found matching 'build' 20:39:23 INFO - no previously-included directories found matching 'dist' 20:39:23 INFO - no previously-included directories found matching '.git*' 20:39:23 INFO - Successfully installed jsonschema functools32 20:39:23 INFO - Cleaning up... 20:39:23 INFO - Return code: 0 20:39:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:39:23 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')]} 20:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:23 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')]} 20:39:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:23 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 0x10523b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fc1d4b1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10504fd30>, '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 0x104e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105047b20>, '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.nIGLod8ueD/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.isQEDwjUKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 20:39:23 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 20:39:23 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 20:39:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIGLod8ueD/Render', 20:39:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:23 INFO - 'HOME': '/Users/cltbld', 20:39:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:23 INFO - 'LOGNAME': 'cltbld', 20:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:23 INFO - 'MOZ_NO_REMOTE': '1', 20:39:23 INFO - 'NO_EM_RESTART': '1', 20:39:23 INFO - 'PAGER': '/bin/cat', 20:39:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:23 INFO - 'PWD': '/builds/slave/test', 20:39:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:23 INFO - 'SHELL': '/bin/bash', 20:39:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isQEDwjUKk/Listeners', 20:39:23 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 20:39:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:23 INFO - 'USER': 'cltbld', 20:39:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:23 INFO - 'XPC_FLAGS': '0x0', 20:39:23 INFO - 'XPC_SERVICE_NAME': '0', 20:39:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:39:23 INFO - Cleaning up... 20:39:23 INFO - Return code: 0 20:39:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:39:23 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')]} 20:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:23 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')]} 20:39:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:24 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 0x10523b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fc1d4b1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10504fd30>, '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 0x104e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105047b20>, '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.nIGLod8ueD/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.isQEDwjUKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 20:39:24 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 20:39:24 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 20:39:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIGLod8ueD/Render', 20:39:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:24 INFO - 'HOME': '/Users/cltbld', 20:39:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:24 INFO - 'LOGNAME': 'cltbld', 20:39:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:24 INFO - 'MOZ_NO_REMOTE': '1', 20:39:24 INFO - 'NO_EM_RESTART': '1', 20:39:24 INFO - 'PAGER': '/bin/cat', 20:39:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:24 INFO - 'PWD': '/builds/slave/test', 20:39:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:24 INFO - 'SHELL': '/bin/bash', 20:39:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isQEDwjUKk/Listeners', 20:39:24 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 20:39:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:24 INFO - 'USER': 'cltbld', 20:39:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:24 INFO - 'XPC_FLAGS': '0x0', 20:39:24 INFO - 'XPC_SERVICE_NAME': '0', 20:39:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:24 INFO - Downloading/unpacking blobuploader==1.2.4 20:39:24 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 20:39:24 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 20:39:24 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 20:39:24 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 20:39:24 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 20:39:24 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 20:39:26 INFO - Downloading blobuploader-1.2.4.tar.gz 20:39:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:39:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:39:26 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 20:39:26 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 20:39:26 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 20:39:26 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 20:39:26 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 20:39:26 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 20:39:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:39:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:39:27 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 20:39:27 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 20:39:27 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 20:39:27 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 20:39:27 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 20:39:27 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 20:39:27 INFO - Downloading docopt-0.6.1.tar.gz 20:39:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:39:28 INFO - Installing collected packages: blobuploader, requests, docopt 20:39:28 INFO - Running setup.py install for blobuploader 20:39:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:39:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:39:28 INFO - Running setup.py install for requests 20:39:28 INFO - Running setup.py install for docopt 20:39:28 INFO - Successfully installed blobuploader requests docopt 20:39:28 INFO - Cleaning up... 20:39:28 INFO - Return code: 0 20:39:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:28 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 0x10523b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fc1d4b1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10504fd30>, '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 0x104e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105047b20>, '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.nIGLod8ueD/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.isQEDwjUKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 20:39:28 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 20:39:28 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 20:39:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIGLod8ueD/Render', 20:39:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:28 INFO - 'HOME': '/Users/cltbld', 20:39:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:28 INFO - 'LOGNAME': 'cltbld', 20:39:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:28 INFO - 'MOZ_NO_REMOTE': '1', 20:39:28 INFO - 'NO_EM_RESTART': '1', 20:39:28 INFO - 'PAGER': '/bin/cat', 20:39:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:28 INFO - 'PWD': '/builds/slave/test', 20:39:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:28 INFO - 'SHELL': '/bin/bash', 20:39:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isQEDwjUKk/Listeners', 20:39:28 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 20:39:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:28 INFO - 'USER': 'cltbld', 20:39:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:28 INFO - 'XPC_FLAGS': '0x0', 20:39:28 INFO - 'XPC_SERVICE_NAME': '0', 20:39:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:39:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-SJGdjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:39:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-fOwK2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:39:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-C64p8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:39:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-XXD_KR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:39:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-AcGvqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:39:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-kSBQ4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:39:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-QJbktv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:39:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-asNHaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:39:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-pWlSFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:39:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-BMqkrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:39:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-BOBdSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:39:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-dSlP7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:39:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-E0QN3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:39:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-tYQ9Gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:39:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-6aaku_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:39:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-2akCjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:39:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-bR8x_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:39:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:39:30 INFO - Running setup.py install for manifestparser 20:39:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:39:31 INFO - Running setup.py install for mozcrash 20:39:31 INFO - Running setup.py install for mozdebug 20:39:31 INFO - Running setup.py install for mozdevice 20:39:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:39:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:39:31 INFO - Running setup.py install for mozfile 20:39:31 INFO - Running setup.py install for mozhttpd 20:39:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:39:31 INFO - Running setup.py install for mozinfo 20:39:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:39:31 INFO - Running setup.py install for mozInstall 20:39:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:39:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:39:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:39:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:39:32 INFO - Running setup.py install for mozleak 20:39:32 INFO - Running setup.py install for mozlog 20:39:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:39:32 INFO - Running setup.py install for moznetwork 20:39:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:39:32 INFO - Running setup.py install for mozprocess 20:39:32 INFO - Running setup.py install for mozprofile 20:39:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:39:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:39:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:39:32 INFO - Running setup.py install for mozrunner 20:39:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:39:33 INFO - Running setup.py install for mozscreenshot 20:39:33 INFO - Running setup.py install for moztest 20:39:33 INFO - Running setup.py install for mozversion 20:39:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:39:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:39:33 INFO - Cleaning up... 20:39:33 INFO - Return code: 0 20:39:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:39: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')]} 20:39:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39: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')]} 20:39:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:33 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 0x10523b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fc1d4b1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10504fd30>, '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 0x104e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105047b20>, '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.nIGLod8ueD/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.isQEDwjUKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 20:39:33 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 20:39:33 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 20:39:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIGLod8ueD/Render', 20:39:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:33 INFO - 'HOME': '/Users/cltbld', 20:39:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:33 INFO - 'LOGNAME': 'cltbld', 20:39:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:33 INFO - 'MOZ_NO_REMOTE': '1', 20:39:33 INFO - 'NO_EM_RESTART': '1', 20:39:33 INFO - 'PAGER': '/bin/cat', 20:39:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:33 INFO - 'PWD': '/builds/slave/test', 20:39:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:33 INFO - 'SHELL': '/bin/bash', 20:39:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isQEDwjUKk/Listeners', 20:39:33 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 20:39:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:33 INFO - 'USER': 'cltbld', 20:39:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:33 INFO - 'XPC_FLAGS': '0x0', 20:39:33 INFO - 'XPC_SERVICE_NAME': '0', 20:39:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:39:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-vMXPbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:39:33 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)) 20:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:39:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-SxHIAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 20:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:39:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-CyxIxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:39:34 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)) 20:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:39:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-kd_RQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:39:34 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)) 20:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:39:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-mySp58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:39:34 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)) 20:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:39:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-1iz_BZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:39:34 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)) 20:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:39:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-B6ts9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:39:34 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)) 20:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:39:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-pE9ywf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:39:34 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)) 20:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:39:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-0U3xEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:39:34 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)) 20:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:39:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-qsTDfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:39:34 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)) 20:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:39:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-ciWGbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:39:34 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)) 20:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:39:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-MjySm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:39:35 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)) 20:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:39:35 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-xgwKJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:39:35 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)) 20:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:39:35 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-OIEm3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 20:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:39:35 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-gbLgHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:39:35 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)) 20:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:39:35 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-5xSUpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:39:35 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-09RXAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:39:35 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)) 20:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:39:35 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)) 20:39:35 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)) 20:39:35 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)) 20:39:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:39:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:39:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:39:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:39:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:39:38 INFO - Downloading blessings-1.6.tar.gz 20:39:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:39:38 INFO - Installing collected packages: blessings 20:39:38 INFO - Running setup.py install for blessings 20:39:38 INFO - Successfully installed blessings 20:39:38 INFO - Cleaning up... 20:39:38 INFO - Return code: 0 20:39:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:39:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:39:38 INFO - Reading from file tmpfile_stdout 20:39:38 INFO - Current package versions: 20:39:38 INFO - blessings == 1.6 20:39:38 INFO - blobuploader == 1.2.4 20:39:38 INFO - docopt == 0.6.1 20:39:38 INFO - functools32 == 3.2.3-2 20:39:38 INFO - jsonschema == 2.5.1 20:39:38 INFO - manifestparser == 1.1 20:39:38 INFO - mozInstall == 1.12 20:39:38 INFO - mozcrash == 0.17 20:39:38 INFO - mozdebug == 0.1 20:39:38 INFO - mozdevice == 0.48 20:39:38 INFO - mozfile == 1.2 20:39:38 INFO - mozhttpd == 0.7 20:39:38 INFO - mozinfo == 0.9 20:39:38 INFO - mozleak == 0.1 20:39:38 INFO - mozlog == 3.4 20:39:38 INFO - moznetwork == 0.27 20:39:38 INFO - mozprocess == 0.23 20:39:38 INFO - mozprofile == 0.28 20:39:38 INFO - mozrunner == 6.12 20:39:38 INFO - mozscreenshot == 0.1 20:39:38 INFO - mozsystemmonitor == 0.3 20:39:38 INFO - moztest == 0.7 20:39:38 INFO - mozversion == 1.4 20:39:38 INFO - psutil == 3.1.1 20:39:38 INFO - requests == 1.2.3 20:39:38 INFO - wsgiref == 0.1.2 20:39:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:39: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')]} 20:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39: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')]} 20:39:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:38 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 0x10523b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fc1d4b1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10504fd30>, '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 0x104e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105047b20>, '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.nIGLod8ueD/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.isQEDwjUKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 20:39:38 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 20:39:38 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 20:39:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIGLod8ueD/Render', 20:39:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:38 INFO - 'HOME': '/Users/cltbld', 20:39:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:38 INFO - 'LOGNAME': 'cltbld', 20:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:38 INFO - 'MOZ_NO_REMOTE': '1', 20:39:38 INFO - 'NO_EM_RESTART': '1', 20:39:38 INFO - 'PAGER': '/bin/cat', 20:39:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:38 INFO - 'PWD': '/builds/slave/test', 20:39:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:38 INFO - 'SHELL': '/bin/bash', 20:39:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isQEDwjUKk/Listeners', 20:39:38 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 20:39:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:38 INFO - 'USER': 'cltbld', 20:39:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:38 INFO - 'XPC_FLAGS': '0x0', 20:39:38 INFO - 'XPC_SERVICE_NAME': '0', 20:39:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:39 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)) 20:39:39 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)) 20:39:39 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)) 20:39:39 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)) 20:39:39 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)) 20:39:39 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)) 20:39:39 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)) 20:39:39 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)) 20:39:39 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)) 20:39:39 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)) 20:39:39 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)) 20:39:39 INFO - Cleaning up... 20:39:39 INFO - Return code: 0 20:39:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:39:39 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')]} 20:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:39 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')]} 20:39:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:39 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 0x10523b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fc1d4b1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10504fd30>, '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 0x104e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105047b20>, '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.nIGLod8ueD/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.isQEDwjUKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 20:39:39 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 20:39:39 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 20:39:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIGLod8ueD/Render', 20:39:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:39 INFO - 'HOME': '/Users/cltbld', 20:39:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:39 INFO - 'LOGNAME': 'cltbld', 20:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:39 INFO - 'MOZ_NO_REMOTE': '1', 20:39:39 INFO - 'NO_EM_RESTART': '1', 20:39:39 INFO - 'PAGER': '/bin/cat', 20:39:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:39 INFO - 'PWD': '/builds/slave/test', 20:39:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:39 INFO - 'SHELL': '/bin/bash', 20:39:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isQEDwjUKk/Listeners', 20:39:39 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 20:39:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:39 INFO - 'USER': 'cltbld', 20:39:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:39 INFO - 'XPC_FLAGS': '0x0', 20:39:39 INFO - 'XPC_SERVICE_NAME': '0', 20:39:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:39:39 INFO - Cleaning up... 20:39:39 INFO - Return code: 0 20:39:39 INFO - Running post-action listener: _resource_record_post_action 20:39:39 INFO - Running post-action listener: _start_resource_monitoring 20:39:39 INFO - Starting resource monitoring. 20:39:39 INFO - [mozharness: 2016-10-17 03:39:39.377431Z] Finished create-virtualenv step (success) 20:39:39 INFO - [mozharness: 2016-10-17 03:39:39.378521Z] Running install step. 20:39:39 INFO - Running pre-action listener: _resource_record_pre_action 20:39:39 INFO - Running main action method: install 20:39:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:39:39 INFO - Reading from file tmpfile_stdout 20:39:39 INFO - Detecting whether we're running mozinstall >=1.0... 20:39:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:39:39 INFO - Reading from file tmpfile_stdout 20:39:39 INFO - Output received: 20:39:39 INFO - Usage: mozinstall [options] installer 20:39:39 INFO - Options: 20:39:39 INFO - -h, --help show this help message and exit 20:39:39 INFO - -d DEST, --destination=DEST 20:39:39 INFO - Directory to install application into. [default: 20:39:39 INFO - "/builds/slave/test"] 20:39:39 INFO - --app=APP Application being installed. [default: firefox] 20:39:39 INFO - mkdir: /builds/slave/test/build/application 20:39:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:40:14 INFO - Reading from file tmpfile_stdout 20:40:14 INFO - Output received: 20:40:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:40:14 INFO - Running post-action listener: _resource_record_post_action 20:40:14 INFO - [mozharness: 2016-10-17 03:40:14.408874Z] Finished install step (success) 20:40:14 INFO - [mozharness: 2016-10-17 03:40:14.409027Z] Running run-tests step. 20:40:14 INFO - Running pre-action listener: _resource_record_pre_action 20:40:14 INFO - Running main action method: run_tests 20:40:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:40:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:40:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:40:14 INFO - 2016-10-16 20:40:14.423 screenresolution[1978:20358] starting screenresolution argv=screenresolution get 20:40:14 INFO - 2016-10-16 20:40:14.426 screenresolution[1978:20358] Display 0: 1600x1200x32@60 20:40:14 INFO - 2016-10-16 20:40:14.432 screenresolution[1979:20361] starting screenresolution argv=screenresolution list 20:40:14 INFO - Available Modes on Display 0 20:40:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:40:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:40:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:40:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:40:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:40:14 INFO - Intel Iris: 20:40:14 INFO - Chipset Model: Intel Iris 20:40:14 INFO - Type: GPU 20:40:14 INFO - Bus: Built-In 20:40:14 INFO - VRAM (Dynamic, Max): 1536 MB 20:40:14 INFO - Vendor: Intel (0x8086) 20:40:14 INFO - Device ID: 0x0a2e 20:40:14 INFO - Revision ID: 0x0009 20:40:14 INFO - Displays: 20:40:14 INFO - Display: 20:40:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:40:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:40:14 INFO - Display Serial Number: 1600x1200 60 20:40:14 INFO - Main Display: Yes 20:40:14 INFO - Mirror: Off 20:40:14 INFO - Online: Yes 20:40:14 INFO - Rotation: Supported 20:40:14 INFO - Return code: 0 20:40:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:40:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:40:14 INFO - Python 2.7.3 20:40:14 INFO - Return code: 0 20:40:14 INFO - grabbing minidump binary from tooltool 20:40:14 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')]} 20:40:14 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 0x10504fd30>, '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 0x104e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105047b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:40:14 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 20:40:14 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 20:40:14 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 20:40:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:40:14 INFO - Return code: 0 20:40:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:40:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:40:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:40:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:40:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:40:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--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 20:40:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0180 --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 20:40:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIGLod8ueD/Render', 20:40:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:40:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:40:14 INFO - 'HOME': '/Users/cltbld', 20:40:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:40:14 INFO - 'LOGNAME': 'cltbld', 20:40:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:40:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:40:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:40:14 INFO - 'MOZ_NO_REMOTE': '1', 20:40:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:40:14 INFO - 'NO_EM_RESTART': '1', 20:40:14 INFO - 'PAGER': '/bin/cat', 20:40:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:40:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:40:14 INFO - 'PWD': '/builds/slave/test', 20:40:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:40:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:40:14 INFO - 'SHELL': '/bin/bash', 20:40:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isQEDwjUKk/Listeners', 20:40:14 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 20:40:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:40:14 INFO - 'USER': 'cltbld', 20:40:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:40:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:40:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:40:14 INFO - 'XPC_FLAGS': '0x0', 20:40:14 INFO - 'XPC_SERVICE_NAME': '0', 20:40:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:40:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--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 20:40:15 INFO - mozversion application_buildid: 20161016193534 20:40:15 INFO - mozversion application_changeset: 013a4088a2307fa31995331c4d18b47fbf75a80b 20:40:15 INFO - mozversion application_display_name: Nightly 20:40:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:40:15 INFO - mozversion application_name: Firefox 20:40:15 INFO - mozversion application_remotingname: firefox 20:40:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 20:40:15 INFO - mozversion application_vendor: Mozilla 20:40:15 INFO - mozversion application_version: 52.0a1 20:40:15 INFO - mozversion platform_buildid: 20161016193534 20:40:15 INFO - mozversion platform_changeset: 013a4088a2307fa31995331c4d18b47fbf75a80b 20:40:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 20:40:15 INFO - mozversion platform_version: 52.0a1 20:40:15 INFO - using testdate: 1476675615 20:40:15 INFO - actual date: 1476675615 20:40:15 INFO - starting webserver on 'localhost:49697' 20:40:15 INFO - SUITE-START | Running 2 tests 20:40:15 INFO - TEST-START | dromaeo_css 20:40:15 INFO - operating with platform_type : mac_ 20:40:15 INFO - Initialising browser for dromaeo_css test... 20:40:15 INFO - TEST-INFO | started process 1989 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpcU7dyH/profile http://localhost:49697/getInfo.html) 20:40:17 INFO - PROCESS | 1989 | __metrics Screen width/height:1600/1200 20:40:17 INFO - PROCESS | 1989 | colorDepth:24 20:40:17 INFO - PROCESS | 1989 | Browser inner width/height: 1024/689 20:40:17 INFO - PROCESS | 1989 | __metrics 20:40:17 INFO - PROCESS | 1989 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:40:17 INFO - PROCESS | 1989 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:40:18 INFO - TEST-INFO | 1989: exit 0 20:40:18 INFO - Browser initialized. 20:40:18 INFO - Running cycle 1/1 for dromaeo_css test... 20:40:18 INFO - TEST-INFO | started process 1991 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpcU7dyH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:40:30 INFO - PROCESS | 1991 | RSS: Main: 231862272 20:40:30 INFO - PROCESS | 1991 | 20:41:22 INFO - PROCESS | 1991 | 1476675682253 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:43:35 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49697/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49697/tests/dromaeo/cssquery-ext.html) 20:43:37 INFO - PROCESS | 1991 | RSS: Main: 400740352 20:43:37 INFO - PROCESS | 1991 | 20:46:39 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49697/tests/dromaeo/cssquery-ext.html (next: http://localhost:49697/tests/dromaeo/cssquery-jquery.html) 20:46:40 INFO - PROCESS | 1991 | RSS: Main: 371904512 20:46:40 INFO - PROCESS | 1991 | 20:49:42 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49697/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49697/tests/dromaeo/cssquery-mootools.html) 20:49:43 INFO - PROCESS | 1991 | RSS: Main: 338751488 20:49:43 INFO - PROCESS | 1991 | 20:52:45 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49697/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49697/tests/dromaeo/cssquery-prototype.html) 20:52:46 INFO - PROCESS | 1991 | RSS: Main: 344588288 20:52:46 INFO - PROCESS | 1991 | 20:55:49 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49697/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49697/tests/dromaeo/cssquery-yui.html) 20:55:50 INFO - PROCESS | 1991 | RSS: Main: 331386880 20:55:50 INFO - PROCESS | 1991 | 20:58:53 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49697/tests/dromaeo/cssquery-yui.html (next: http://localhost:49697/tests/dromaeo/cssquery-dojo.html) 20:58:53 INFO - PROCESS | 1991 | RSS: Main: 408551424 20:58:53 INFO - PROCESS | 1991 | 20:58:53 INFO - PROCESS | 1991 | __start_tp_report 20:58:53 INFO - PROCESS | 1991 | _x_x_mozilla_page_load 20:58:53 INFO - PROCESS | 1991 | _x_x_mozilla_page_load_details 20:58:53 INFO - PROCESS | 1991 | |i|pagename|runs| 20:58:53 INFO - PROCESS | 1991 | |0;dojo.html;3238.1418923985148;3257;3271.50927360896;3271.9672803271974;3316.8009919404835;4650.372199753032;5470.863228419292;5585.916211256835;5611.270534830472;5630.1554766785;5570.6657600543995;5580.246666699994;5589.692566908819;5594.272744543204;5597.496225339719;5064.3163172971545;5106.923396149061;5122.871928201806;5142.305788718531;5197.662151960129;5129.281900533928;5180.2488639147305;5192.974035129838;5198.610104242178;5215.191645294985;5111.335526381584;5139.691618502883;5173.689578625294;5173.896522069556;5195.948040519603;5216.843494695166;5232.529072383466;5249.86875328116;5262.079136151158;5308.018016666929;4983.72589507577;5021.648484606079;5167.741612919339;5189.896202075937;5202.713850738208;5539.418361072069;5542.334919809648;5550.91673624896;5576.776928922838;5592.077307244341;6073.1497590337385;6106.816795496142;6134.723937422782;6139.693015349259;6142.477889379408;6103.115048317969;6108.694565271719;6114.71942267015;6175.197224360849;6184.165137706397;5672.801451949162;5689.829305120853;5693.4875861172695;5720.399358067343;5759.65442073477;6210.5652604317265;6213.130161777355;6219.315875253718;6226.283977342573;6241.188645476059;5908.734106965197;6043.939560604338;6062.333143354227;6076.635401875901;6077.544184186204;6084.30030546484;6112.052631842072;6121.574489287494;6138.633639632459;6139.185504090456;5570.6657600544095;5718.456746609066;6095.455447509409;6135.294441139237;6179.62922224668;6106.755729770759;6158.753649854045;6163.691815409212;6170.197874276315;6180.66006369645;4875.609951203895;4880.6583539152625;4888.755562221874;4890.315355850184;4919.163742163841;4304.289792184317;4326.048269380732;4339.262325404688;4349.347597860283;4355.412019377407;5567.387587365387;5571.13647384656;5585.748641311151;5593.412691667398;5641.80253691127;6131.724072416663;6158.876822463526;6188.1027251048345;6189.473894718999;6191.690415479208;5430.239766432703;5486.204500347428;5494.5054945055135;5590.105583106683;5604;4530.275155975094;5268.104422248227;5305.389880163831;5315.176147697113;5371.221172929903;6186;6190.876182476415;6190.969045154746;6195.473384762256;6209.379062093754;5979.342272349951;6160.630362178284;6181.629102253879;6199.814005579839;6200.627962322275;6118.942928706938;6150.200473938359;6202.627842329474;6205.441510264098;6207.65857877803;6041.708043694141;6108.311716707834;6112.1995365628145;6116.792316687851;6131.804871721949;4982.950170498249;6191.659458729813;6198.1012753149635;6201.3178550358525;6201.875962480815;5802.477776999922;5864.354920958776;5870.354261031369;5913.438223368773;5981.461668449861;6004.249468816398;6089.238845144357;6114.357992410733;6116.204893363834;6121.265448146251;5896.321922978912;5900.763969441175;5902.586818922634;5917.260342457193;5925.911111333247;5968.373320801427;5980.790672326448;6032.819015429544;6035.486983606443;6052.092186172109;5679.176519404746;5683.175939488834;5704.344000440003;5732.34078081026;5816.592838501433 20:58:53 INFO - PROCESS | 1991 | |1;ext.html;469.93655856459367;475.81443237137523;508.65207200917587;509.71710700561187;509.9698608810202;24828;34639.826800865994;40662.79668601657;41933.79033104838;42183.78908105463;42651.78674106633;43133.568664313345;43141.56858431415;43216.351754723706;43281.567184328145;9453.763655908606;9599.856002159973;9602.231821454267;9604.135627793497;9617.182539484153;9479.24166066713;9596.376235544667;9605.423674579513;9608.279379046564;9622.470764107971;9592.280578956606;9637.951810240938;9647.469389183592;9649.276304277173;9670;9550.235981121528;9602.327837051409;9606.13544780966;9609.807803843954;9625.470599117045;9398.765030874214;9478.478683672396;9490.525473726355;9497;9516.714498565054;40391.99020024494;40675.1864962702;40772.38841417381;40828;40844.1831163378;3361.1261072121424;3399.915002124942;3444.0528854564946;3700.2229531798353;3949.545802232751;30218.395632087235;31466.89865854707;31507.684923150704;31578.368432631218;32021.039368818972;3141.3246152077186;3241.173500757292;3267.77125601208;3282.786618869766;3337.7830441021256;464602;466123;466928.66535667446;466986;471118;59421.405785941584;59876.70061649664;59885.10172347418;59991;60037;63435;63457.682711586145;63469.68265158737;63557.36442635607;63646.36353636497;15987;16178.514644560682;16201.837981620269;16229.026258424532;16237.67524649524;12531.498740050296;12572.371381431074;12584.244945303311;12613.369331533386;12635.620931372074;13618.795718064237;14143.504977325743;16577.17114144288;16617.0860602668;16773.72270071124;5723.45627165427;6820.420264277493;6977.406920411822;7062.788116356517;7123.643817809192;745.8545583611144;971.4990339924833;1054.330500132406;1060.5015642647943;1066.6586692258559;28411.289717757223;29902.850485747433;30057.549136762966;30102.24744381408;30328.090157295315;979.1921664626711;1031.6904928521415;1112.5883423133491;1127.1940562497853;1149.7872893514727;15127.54617361481;15621.921890390475;15652.843471565366;15806.419358064102;15819.446319378996;595828;598142;598466;600149;603205;58649.82700345969;58828.41171588315;58857.70571147117;58876.70561647164;58876.70561647164;1242.726600147966;1285.916415432994;1346.8181795457488;1381.247220264958;1409.5348534983432;28819.855900720362;29644.66599003007;31334.993487471238;31437.52843707392;32123.35753284968;50075.248871267686;50660;50792.746036269586;50794.492055078976;50797.23804142867;423082.88381068164;425724;426145;426786;427027;23553.41116472102;25724.09965651193;26678;26696.46607067809;27602.033928812394;47104.29343559918;47148.292775607704;47548.049039019716;48273;48312.275315871;36116.63883361133;38905.6109438902;39361.40957885577;39453;40115.59884401118;38935.61064389433;39017.60982390253;40252;40350.9912252196;40597.1880562393;37407.625923740416;37676.43485347667;38233.23533529369;38542.22915541617;38932.221355573296;26425.207243782716;29859.402811943204;33137.834310829254;34248.65751342554;34611.4808277871;23194.072237110326;25399.238022859343;25629.949604032005;26107;26316.947102166992 20:58:53 INFO - PROCESS | 1991 | |2;jquery.html;351.115189721901;361.0576395607462;368.9299033183695;379.86135060702844;397.8110397561157;14704.705905881889;15021.24893755312;15712.607184820385;19910;20319.593608127827;19724.211031558756;20585.07367168477;20779.792202077973;20800.959952002377;21013.94930253485;5168.741562921858;5719.399463056381;5994;6009.909851352233;6014.609050411731;5249.685018898878;5914.9112763308585;5974.3428222895445;5976.193213916109;5982.491488223506;5650.632708873931;5897.233359663238;5949.077892926603;5950.9107363389585;5953.3748956359605;5873.70631468427;5927.8221653350465;5931.555133365015;5931.792387266425;5941.584089113764;5098.949010509885;5159.458256883011;5166.405863325728;5355.839324820262;5424.755885985141;12836.358182090858;13090.86909130906;13098.47606095755;14194.57416277503;24407.389815254584;1843.216632930999;2000.603843812334;2042.8774273543636;2207.834412419075;2824.7457728804507;9681.273904457123;9755.268354873413;9804.264680149017;10161.59353625854;10208.540615672313;1715.6139868529606;1760.9735853962202;1771.9645607087787;1795.4075155198752;1901.2300018492533;114870;124691;125232.37383813114;126341.36829315887;129866.35066824606;10405.323653962469;10730.409827459565;11298.435078246055;11378.772424551462;11422;14454.638634034232;14458.349374278183;14489.420423183166;14516.854831451761;14521.636959076108;8029.478083924526;8408.579571021424;8408.915910840935;8500.97988241415;8546.034412085131;6897.827554311182;8288.544130072903;8304.87884135634;8339.041010283894;8450.87323690145;4459.572936660237;4465.732056076646;4469.374287599739;4479.619232365286;4566.200914839957;1641.8850680452254;1864.683003889315;1909.6562618728485;1919.0692514130635;1924.7978962209047;884.5090974509209;972.8248915195333;979.5983646704817;998.5706146356991;1009.2733232072895;19796;19952.3016694415;21549;21618.351449456542;21808.563828723654;845.222395396237;871.8212766382876;928.3176865004217;947.4930911962067;1019.8521214423866;9536.879928136457;10949.83575246372;11143.33140011602;11203.358908354654;11340.149488788375;576886.8136757473;577334;583427;594814;595067;99486;123496.38251808683;129236;129863.35068324598;133790;1024.5440778853338;1028.9331193472551;1056.6671498477954;1057.3867157048894;1060.7242117049623;17826.643467130845;18345.54136146607;18370.805897616614;18459;18758.59310551687;51598.74200628973;51994.70013249699;52710;53491.19763203633;53578;72084;75017.24982750103;77062.6146869262;78758;86852.56573717344;7030;7280.0535930328715;7289.5261807982315;7342.6695798688115;7408.073990751156;9826.508674566387;11724.70688232767;12399.814002790146;12874.420651070537;13241.602751917459;10140.391576505433;11251.437428128724;11455.942720286344;11468.31190128595;11654.30074195551;11183.664490065312;11200.943995279971;11474.368909709712;11474.655760327203;11794.174407791716;11499.195056345976;11719.824202637137;11742.941285293518;11890.583829566269;11949.342786146846;7199.208087110309;7235.2764723527225;7349.118105827335;7562.848743024999;7565;10224.795504089729;10257.538410771682;11024.393658348868;11477.85221477879;11623.476943537393 20:58:53 INFO - PROCESS | 1991 | |3;mootools.html;1380.6203294094116;1554.5491807375865;1579.928903199356;1813.5103522049046;1959.5590992026796;7866.528008319505;8319.209675080858;8319.417640765148;8323.708670196536;8352.498850069;8097.2307630774985;8538.231559159674;8694.913050869489;8807.647694092244;8873.86689199661;2267.036509483471;2297.8506397084134;2298.977010229893;2303.9654405183937;2304.83866129371;2328.092044102801;2367.065009321317;2371.8932648030795;2374.9406264843433;2376.988115059431;2337.8947947342413;2363.078399424226;2365.290412876139;2382.1543352109925;2390.5816482115647;2299.597570425169;2324.1749179041367;2339.742628310893;2341.1454818991115;2343.086196383395;2047.6314263432575;2327.7090363704538;2328.2549584133085;2329.196427232619;2330.9650355244685;8761.386702930797;8831.116888311182;8832.470051796912;8845.823083538293;8858.247049000842;5125.564327032207;5134.255532947702;5309.256704061435;5309.893802123975;5335.706536140511;3426.880059197916;3580.1407662160896;3660.5973342932257;3667.6332366763377;3674.430463278196;1050.1441325319915;1056.6988408303673;1070.4968664727644;1072.0833687197412;1077.784443111373;11211.495482703298;11483.77032459346;11546.36494992775;11573.016293615054;11648.94175529117;4381.824727010884;4490.124425737015;4582.404287442611;4608.861734147981;4631.95368046322;9022.729318120468;9032.23226025782;9057.592408341641;9057.81884362309;9070.682526111554;5191.065608190486;5191.117510023305;5195.792168313225;5200.76596553156;5200.985807767524;4117.1765646870745;4359;4521.683482156283;4522.140793249272;4880.829170978998;3656.0494271489074;3692;3761.1161377076915;3761.22894806564;3762.8682996095554;1133.7619099989095;1139.897409233173;1149.5918948773233;1152.3546813784308;1153.8038533449335;761.5278527313102;856.5289090999925;891.3671293381642;910.7040211931148;943.6225509796;5341.664583854036;5607.4112218217315;5793.188953546507;5902.439268269508;5947.31605865331;879.388824766781;908.496438394445;923.8752768376318;968.5931908598405;993.101243374747;3940.054386947112;3951.78265195417;3957.703172262092;4034.677225821927;4083.0813067059676;10490.737731556772;10730.946345268223;10842.891571084188;10843.620473283398;10896.564137434414;10817.945910270399;10827.296225745302;10829.29609575375;10834.295770774874;10845.620403286004;7768.145503994556;8162.918370816335;8192.139825318374;8192.877106843402;8225.917740822635;5883.382244864399;5889.263842019747;5951.940480595312;6088.4215999479975;6136.171616831849;9920.255980801325;9957.850632240377;10000.24995625777;10017.198624109908;10129.696109116738;9273.165415112673;9769.462679552693;10159.949200253952;10241.539130738985;10261.846072308772;7013.894791578028;7203.819904502429;7338.486305958531;7467.813304667427;7558.659860306189;8181.631826567937;8200.26197642215;8205.507669539847;8268.710595129141;8304.6678132874;7453.776386708408;7506.211847755907;7566.848663026819;7569.810754731176;7570.9242907570215;7250.709971601077;7328.487005909536;7371.520851144658;7372.815679608053;7458.701651933862;6743.494237932273;6840.9657951711915;6921.723131074902;6926.376626103674;6931.896021559782;4851.223804191312;4932.802687892444;4938.9259161111895;5004.1492946198505;5163.199704045879;4945.554900059012;5010.899782004412;5064.518870707277;5083.491650834887;5191.636585438983 20:58:53 INFO - PROCESS | 1991 | |4;prototype.html;259.2016588906169;294.77891581314015;300.42168825011845;307.14613374818;309.81565968248884;4858.764968114662;5093.668911520753;5096.413912400075;5122.41092274389;5143.6656617319895;4697.436307643079;5015.648904576681;5077.898442031166;5109.6678715883445;5128.179491281396;3992.2852460326967;3997.5003124609425;4010.6991975601873;4021.8227600195955;4040.9595904040875;3884.9805750971204;4000.6999475039343;4034.616711412426;4055;4061.2689715851134;3915.060385507486;3948.7038472114564;3986.5614782373914;3994.760314381132;4023.6982226333;3409.4885767135006;3644.562652481719;3705.1478160023075;3906.765594064351;3937.88186354407;2917.9854100729362;3197.2006998250436;3388.949165762491;3405.557277553902;3465.7227421806197;4160.9375859362135;4353.5428779977965;4987.351644286219;5025.522575355372;5098.541131298164;4446.888827779299;4449.755263460508;4453.421055262795;4465.218586747339;4498.86304005598;4978.6514943953935;5325.680459172462;5398.676079435208;5445.529706979093;5488.313960754906;3673.971288039353;3689.151495156129;3698.778073315583;3701.0932321581117;3703.740738148331;4975.552200301954;5169.353830771153;5318.6542874713405;5656.462636049569;5702.828915132512;3615.349237137288;3740.2893450244364;3745.9250814984093;3753.9249215016093;3757.304898593769;3722.553293604785;3775.0562359410146;3792.932851595887;3824.598417166213;3841.519810023747;4434.889127771767;4459.777011149495;4462.742198195336;4478.126765280739;4497.730136191839;4774.904501910012;4916.410030796327;5402.6758394496455;5438.91841622376;5462.317210348707;1152.757920836617;1221.9266843989212;1280.7566562353122;1283.6084994076896;1298.5519995601503;692.3768608252512;753.2354660020084;775.9573223472763;789.4197764642983;807.8667019941761;4027.965877231979;4097.221527909687;4211.936820947688;4323.546027667113;4334.5882141196535;3960.4257382679352;4285.528591854893;4490.236659767847;4507.3689683444345;4657.626000329843;3732.533433320835;3818.7517811342173;4118.732282401635;4158.334666453353;4245.957540424618;2325.6627788970504;2328.569214695287;2357.481354102095;2453.178844329036;2945.3078526546246;5365.9950427759795;5681.914771278434;5847.26909136358;5857.677827719431;5864.953182304196;5099.796008159707;5706.9429305707245;5734.369219385864;5748.913766293509;5769.423057694281;4660.460049273086;4821.5178482152205;5736.250443614737;5864.266966629171;5916.319623243382;4764.595009424099;5285.577153827608;5644.379118296902;5645.717714114196;5655.632383895034;4609.792559334771;5281.339832520935;5600.27196464457;5638.351589567253;5653.957420444402;4919.803207871645;5833.212516310244;5880.794172203952;5887.555779868825;5898.790747896756;3232.995542763502;3751.343514884939;3813.1039205786064;3816.31306364857;3837.4819399381845;4852.175130227799;5346.465353464622;5359.464053594609;5678.971605142113;5699.487046165865;4361.912761744811;4899.167141585867;5175.534201921694;5361.276227709365;5413.891722165614;4665.6034237090225;4964.478729733326;5002.074616196015;5358.705271210121;5384.784608615768;4883.6093112551625;5200.6619569727845;5370.62405631602;5405.891882162414;5452.410105200157;4990.326305948651;5204.115300399017;5268;5331.466853314638;5461.317335333083;4485.932711009272;4579.656525760514;5236.764345604381;5346.8395948121615;5374.435684253097 20:58:53 INFO - PROCESS | 1991 | |5;yui.html;710.7313445499781;742.5433358484531;753.935915447187;827.7847759582514;841.4740787008118;15090.622734431645;15854.28655710493;15862.84137158628;15919.840801591981;16206.027638341708;14304.856951430482;14394.568162955102;14414.927925360358;14424;14432.639184020378;699.3775539769609;717.8456631824157;720.3336913355151;721.239092757141;865.0441262405046;716.6022857314189;721.3579913876654;869.9608517616691;899.1143723432414;908.2688435809171;939.3096074385321;946.1012038563358;950;950.0546955779009;953.752024473635;942.3121121581285;942.641796117471;944.5796620503844;945.026622578745;948.5731420860606;705.1432509500981;706.2443185791205;707.6815433055145;707.7841258416181;708.3624737736079;11813.173077884552;12283.955863751591;12289.139760216789;12394.81407778884;12418;12446.128770986035;14402.495912643111;14496.927515362355;14718.675319221322;14872.702545949129;10193.337433066827;11452.828207576893;11680.41597920109;11684.70788230301;11941.402929853559;3803.3724435468116;3831.8467261309793;3842.5581058178386;3961.3463778476516;3966.3852102924093;107005;108139.45930270376;108315;108936.4553177237;109882;11098.944505277583;11148.777024459465;11161;11527.48126334317;11804.704882377837;22765.544689106126;22797.772022279896;22829.885850570972;22833.88583057074;22946.65580016301;6946.895796563055;7620.9618951905995;7852.450328476952;8028.959855200686;8034.879476807852;4128.855490057834;4184.330507118907;4237.427947227085;4243.215005224043;4257.63810076147;3505.9298814023578;3651.9817400912825;3654.6710796028483;3655.409896694893;3671.632836716307;619.4332186049804;619.8946179149556;638.575347393987;640.1549954060596;640.7212862404868;639.469240530368;639.5139693832627;655.9147310849654;675.7938828657209;681.7954613615995;8510.191531804468;8992.8651070234;9189.356745027917;9304;9435.669751558662;649.7043845050491;654.5581732330658;667.5393978155057;670.9329067093252;682.7917485166972;4324.308110702273;4618.792154353062;4624.352590637313;4630.21286381316;4665.953340466552;95081.52459237659;146253;156569;163015;163666;134670;136448.31775841257;138791;138973.30513347368;142063;75587;105622;110725.44637276762;116645.25289086204;117712;9329.58016889228;9340.579673914826;9631.614735410505;9747.512624368896;9843.621892934852;44537.33194002158;51152.976940461725;53561.464385357205;53576.46423535715;56608;62143.367777994485;62806;62808.37191628208;76938.2306176931;82689;11463.656090317303;13190.010749193658;13418.597442076752;13561.118527295694;13946.651333716738;13598.320083995563;13610.659733506345;14748.007430934244;14907.701845963236;15080.849191508383;13955.860441395456;14026.649333766738;14037.719245615235;14213.786793198316;14794.852051479347;13859.099158555066;13879.722405552035;13896.86103138996;13977.58067257984;14436.061655992327;13729.244891531062;13746.587602371945;13789.655258618615;14384.712305753617;14469.421223150955;10573.790529787044;11151.219414640897;11454;11689.240199387013;11910.166288359731;9492.620295188115;10578.206634502289;10690.037896589345;10737.731556711145;11446.828297575377 20:58:53 INFO - PROCESS | 1991 | __end_tp_report 20:58:53 INFO - PROCESS | 1991 | __start_cc_report 20:58:53 INFO - PROCESS | 1991 | _x_x_mozilla_cycle_collect,1685 20:58:53 INFO - PROCESS | 1991 | __end_cc_report 20:58:53 INFO - PROCESS | 1991 | __startTimestamp1476676733661__endTimestamp 20:58:53 INFO - PROCESS | 1991 | 20:58:53 INFO - PROCESS | 1991 | ------- Summary: start ------- 20:58:53 INFO - PROCESS | 1991 | Number of tests: 6 20:58:53 INFO - PROCESS | 1991 | 20:58:53 INFO - PROCESS | 1991 | [#0] dojo.html Cycles:165 Average:5634.59 Median:5802.48 stddev:639.13 (11.0%) stddev-sans-first:612.82 20:58:53 INFO - PROCESS | 1991 | Values: 3238.1 3257.0 3271.5 3272.0 3316.8 4650.4 5470.9 5585.9 5611.3 5630.2 5570.7 5580.2 5589.7 5594.3 5597.5 5064.3 5106.9 5122.9 5142.3 5197.7 5129.3 5180.2 5193.0 5198.6 5215.2 5111.3 5139.7 5173.7 5173.9 5195.9 5216.8 5232.5 5249.9 5262.1 5308.0 4983.7 5021.6 5167.7 5189.9 5202.7 5539.4 5542.3 5550.9 5576.8 5592.1 6073.1 6106.8 6134.7 6139.7 6142.5 6103.1 6108.7 6114.7 6175.2 6184.2 5672.8 5689.8 5693.5 5720.4 5759.7 6210.6 6213.1 6219.3 6226.3 6241.2 5908.7 6043.9 6062.3 6076.6 6077.5 6084.3 6112.1 6121.6 6138.6 6139.2 5570.7 5718.5 6095.5 6135.3 6179.6 6106.8 6158.8 6163.7 6170.2 6180.7 4875.6 4880.7 4888.8 4890.3 4919.2 4304.3 4326.0 4339.3 4349.3 4355.4 5567.4 5571.1 5585.7 5593.4 5641.8 6131.7 6158.9 6188.1 6189.5 6191.7 5430.2 5486.2 5494.5 5590.1 5604.0 4530.3 5268.1 5305.4 5315.2 5371.2 6186.0 6190.9 6191.0 6195.5 6209.4 5979.3 6160.6 6181.6 6199.8 6200.6 6118.9 6150.2 6202.6 6205.4 6207.7 6041.7 6108.3 6112.2 6116.8 6131.8 4983.0 6191.7 6198.1 6201.3 6201.9 5802.5 5864.4 5870.4 5913.4 5981.5 6004.2 6089.2 6114.4 6116.2 6121.3 5896.3 5900.8 5902.6 5917.3 5925.9 5968.4 5980.8 6032.8 6035.5 6052.1 5679.2 5683.2 5704.3 5732.3 5816.6 20:58:53 INFO - PROCESS | 1991 | 20:58:53 INFO - PROCESS | 1991 | [#1] ext.html Cycles:180 Average:64167.05 Median:40668.99 stddev:133938.39 (329.3%) stddev-sans-first:134228.74 20:58:53 INFO - PROCESS | 1991 | Values: 469.9 475.8 508.7 509.7 510.0 24828.0 34639.8 40662.8 41933.8 42183.8 42651.8 43133.6 43141.6 43216.4 43281.6 9453.8 9599.9 9602.2 9604.1 9617.2 9479.2 9596.4 9605.4 9608.3 9622.5 9592.3 9638.0 9647.5 9649.3 9670.0 9550.2 9602.3 9606.1 9609.8 9625.5 9398.8 9478.5 9490.5 9497.0 9516.7 40392.0 40675.2 40772.4 40828.0 40844.2 3361.1 3399.9 3444.1 3700.2 3949.5 30218.4 31466.9 31507.7 31578.4 32021.0 3141.3 3241.2 3267.8 3282.8 3337.8 464602.0 466123.0 466928.7 466986.0 471118.0 59421.4 59876.7 59885.1 59991.0 60037.0 63435.0 63457.7 63469.7 63557.4 63646.4 15987.0 16178.5 16201.8 16229.0 16237.7 12531.5 12572.4 12584.2 12613.4 12635.6 13618.8 14143.5 16577.2 16617.1 16773.7 5723.5 6820.4 6977.4 7062.8 7123.6 745.9 971.5 1054.3 1060.5 1066.7 28411.3 29902.9 30057.5 30102.2 30328.1 979.2 1031.7 1112.6 1127.2 1149.8 15127.5 15621.9 15652.8 15806.4 15819.4 595828.0 598142.0 598466.0 600149.0 603205.0 58649.8 58828.4 58857.7 58876.7 58876.7 1242.7 1285.9 1346.8 1381.2 1409.5 28819.9 29644.7 31335.0 31437.5 32123.4 50075.2 50660.0 50792.7 50794.5 50797.2 423082.9 425724.0 426145.0 426786.0 427027.0 23553.4 25724.1 26678.0 26696.5 27602.0 47104.3 47148.3 47548.0 48273.0 48312.3 36116.6 38905.6 39361.4 39453.0 40115.6 38935.6 39017.6 40252.0 40351.0 40597.2 37407.6 37676.4 38233.2 38542.2 38932.2 26425.2 29859.4 33137.8 34248.7 34611.5 23194.1 25399.2 25629.9 26107.0 26316.9 20:58:53 INFO - PROCESS | 1991 | 20:58:53 INFO - PROCESS | 1991 | [#2] jquery.html Cycles:180 Average:34250.18 Median:21583.68 stddev:97984.28 (454.0%) stddev-sans-first:98226.09 20:58:53 INFO - PROCESS | 1991 | Values: 351.1 361.1 368.9 379.9 397.8 14704.7 15021.2 15712.6 19910.0 20319.6 19724.2 20585.1 20779.8 20801.0 21013.9 5168.7 5719.4 5994.0 6009.9 6014.6 5249.7 5914.9 5974.3 5976.2 5982.5 5650.6 5897.2 5949.1 5950.9 5953.4 5873.7 5927.8 5931.6 5931.8 5941.6 5098.9 5159.5 5166.4 5355.8 5424.8 12836.4 13090.9 13098.5 14194.6 24407.4 1843.2 2000.6 2042.9 2207.8 2824.7 9681.3 9755.3 9804.3 10161.6 10208.5 1715.6 1761.0 1772.0 1795.4 1901.2 114870.0 124691.0 125232.4 126341.4 129866.4 10405.3 10730.4 11298.4 11378.8 11422.0 14454.6 14458.3 14489.4 14516.9 14521.6 8029.5 8408.6 8408.9 8501.0 8546.0 6897.8 8288.5 8304.9 8339.0 8450.9 4459.6 4465.7 4469.4 4479.6 4566.2 1641.9 1864.7 1909.7 1919.1 1924.8 884.5 972.8 979.6 998.6 1009.3 19796.0 19952.3 21549.0 21618.4 21808.6 845.2 871.8 928.3 947.5 1019.9 9536.9 10949.8 11143.3 11203.4 11340.1 576886.8 577334.0 583427.0 594814.0 595067.0 99486.0 123496.4 129236.0 129863.4 133790.0 1024.5 1028.9 1056.7 1057.4 1060.7 17826.6 18345.5 18370.8 18459.0 18758.6 51598.7 51994.7 52710.0 53491.2 53578.0 72084.0 75017.2 77062.6 78758.0 86852.6 7030.0 7280.1 7289.5 7342.7 7408.1 9826.5 11724.7 12399.8 12874.4 13241.6 10140.4 11251.4 11455.9 11468.3 11654.3 11183.7 11200.9 11474.4 11474.7 11794.2 11499.2 11719.8 11742.9 11890.6 11949.3 7199.2 7235.3 7349.1 7562.8 7565.0 10224.8 10257.5 11024.4 11477.9 11623.5 20:58:53 INFO - PROCESS | 1991 | 20:58:53 INFO - PROCESS | 1991 | [#3] mootools.html Cycles:180 Average:5638.72 Median:5007.52 stddev:3160.83 (63.1%) stddev-sans-first:3153.50 20:58:53 INFO - PROCESS | 1991 | Values: 1380.6 1554.5 1579.9 1813.5 1959.6 7866.5 8319.2 8319.4 8323.7 8352.5 8097.2 8538.2 8694.9 8807.6 8873.9 2267.0 2297.9 2299.0 2304.0 2304.8 2328.1 2367.1 2371.9 2374.9 2377.0 2337.9 2363.1 2365.3 2382.2 2390.6 2299.6 2324.2 2339.7 2341.1 2343.1 2047.6 2327.7 2328.3 2329.2 2331.0 8761.4 8831.1 8832.5 8845.8 8858.2 5125.6 5134.3 5309.3 5309.9 5335.7 3426.9 3580.1 3660.6 3667.6 3674.4 1050.1 1056.7 1070.5 1072.1 1077.8 11211.5 11483.8 11546.4 11573.0 11648.9 4381.8 4490.1 4582.4 4608.9 4632.0 9022.7 9032.2 9057.6 9057.8 9070.7 5191.1 5191.1 5195.8 5200.8 5201.0 4117.2 4359.0 4521.7 4522.1 4880.8 3656.0 3692.0 3761.1 3761.2 3762.9 1133.8 1139.9 1149.6 1152.4 1153.8 761.5 856.5 891.4 910.7 943.6 5341.7 5607.4 5793.2 5902.4 5947.3 879.4 908.5 923.9 968.6 993.1 3940.1 3951.8 3957.7 4034.7 4083.1 10490.7 10730.9 10842.9 10843.6 10896.6 10817.9 10827.3 10829.3 10834.3 10845.6 7768.1 8162.9 8192.1 8192.9 8225.9 5883.4 5889.3 5951.9 6088.4 6136.2 9920.3 9957.9 10000.2 10017.2 10129.7 9273.2 9769.5 10159.9 10241.5 10261.8 7013.9 7203.8 7338.5 7467.8 7558.7 8181.6 8200.3 8205.5 8268.7 8304.7 7453.8 7506.2 7566.8 7569.8 7570.9 7250.7 7328.5 7371.5 7372.8 7458.7 6743.5 6841.0 6921.7 6926.4 6931.9 4851.2 4932.8 4938.9 5004.1 5163.2 4945.6 5010.9 5064.5 5083.5 5191.6 20:58:53 INFO - PROCESS | 1991 | 20:58:53 INFO - PROCESS | 1991 | [#4] prototype.html Cycles:180 Average:4307.82 Median:4659.04 stddev:1342.16 (28.8%) stddev-sans-first:1311.07 20:58:53 INFO - PROCESS | 1991 | Values: 259.2 294.8 300.4 307.1 309.8 4858.8 5093.7 5096.4 5122.4 5143.7 4697.4 5015.6 5077.9 5109.7 5128.2 3992.3 3997.5 4010.7 4021.8 4041.0 3885.0 4000.7 4034.6 4055.0 4061.3 3915.1 3948.7 3986.6 3994.8 4023.7 3409.5 3644.6 3705.1 3906.8 3937.9 2918.0 3197.2 3388.9 3405.6 3465.7 4160.9 4353.5 4987.4 5025.5 5098.5 4446.9 4449.8 4453.4 4465.2 4498.9 4978.7 5325.7 5398.7 5445.5 5488.3 3674.0 3689.2 3698.8 3701.1 3703.7 4975.6 5169.4 5318.7 5656.5 5702.8 3615.3 3740.3 3745.9 3753.9 3757.3 3722.6 3775.1 3792.9 3824.6 3841.5 4434.9 4459.8 4462.7 4478.1 4497.7 4774.9 4916.4 5402.7 5438.9 5462.3 1152.8 1221.9 1280.8 1283.6 1298.6 692.4 753.2 776.0 789.4 807.9 4028.0 4097.2 4211.9 4323.5 4334.6 3960.4 4285.5 4490.2 4507.4 4657.6 3732.5 3818.8 4118.7 4158.3 4246.0 2325.7 2328.6 2357.5 2453.2 2945.3 5366.0 5681.9 5847.3 5857.7 5865.0 5099.8 5706.9 5734.4 5748.9 5769.4 4660.5 4821.5 5736.3 5864.3 5916.3 4764.6 5285.6 5644.4 5645.7 5655.6 4609.8 5281.3 5600.3 5638.4 5654.0 4919.8 5833.2 5880.8 5887.6 5898.8 3233.0 3751.3 3813.1 3816.3 3837.5 4852.2 5346.5 5359.5 5679.0 5699.5 4361.9 4899.2 5175.5 5361.3 5413.9 4665.6 4964.5 5002.1 5358.7 5384.8 4883.6 5200.7 5370.6 5405.9 5452.4 4990.3 5204.1 5268.0 5331.5 5461.3 4485.9 4579.7 5236.8 5346.8 5374.4 20:58:53 INFO - PROCESS | 1991 | 20:58:53 INFO - PROCESS | 1991 | [#5] yui.html Cycles:180 Average:23933.53 Median:3663.52 stddev:38818.61 (1059.6%) stddev-sans-first:38888.34 20:58:53 INFO - PROCESS | 1991 | Values: 710.7 742.5 753.9 827.8 841.5 15090.6 15854.3 15862.8 15919.8 16206.0 14304.9 14394.6 14414.9 14424.0 14432.6 699.4 717.8 720.3 721.2 865.0 716.6 721.4 870.0 899.1 908.3 939.3 946.1 950.0 950.1 953.8 942.3 942.6 944.6 945.0 948.6 705.1 706.2 707.7 707.8 708.4 11813.2 12284.0 12289.1 12394.8 12418.0 12446.1 14402.5 14496.9 14718.7 14872.7 10193.3 11452.8 11680.4 11684.7 11941.4 3803.4 3831.8 3842.6 3961.3 3966.4 107005.0 108139.5 108315.0 108936.5 109882.0 11098.9 11148.8 11161.0 11527.5 11804.7 22765.5 22797.8 22829.9 22833.9 22946.7 6946.9 7621.0 7852.5 8029.0 8034.9 4128.9 4184.3 4237.4 4243.2 4257.6 3505.9 3652.0 3654.7 3655.4 3671.6 619.4 619.9 638.6 640.2 640.7 639.5 639.5 655.9 675.8 681.8 8510.2 8992.9 9189.4 9304.0 9435.7 649.7 654.6 667.5 670.9 682.8 4324.3 4618.8 4624.4 4630.2 4666.0 95081.5 146253.0 156569.0 163015.0 163666.0 134670.0 136448.3 138791.0 138973.3 142063.0 75587.0 105622.0 110725.4 116645.3 117712.0 9329.6 9340.6 9631.6 9747.5 9843.6 44537.3 51153.0 53561.5 53576.5 56608.0 62143.4 62806.0 62808.4 76938.2 82689.0 11463.7 13190.0 13418.6 13561.1 13946.7 13598.3 13610.7 14748.0 14907.7 15080.8 13955.9 14026.6 14037.7 14213.8 14794.9 13859.1 13879.7 13896.9 13977.6 14436.1 13729.2 13746.6 13789.7 14384.7 14469.4 10573.8 11151.2 11454.0 11689.2 11910.2 9492.6 10578.2 10690.0 10737.7 11446.8 20:58:53 INFO - PROCESS | 1991 | -------- Summary: end -------- 20:58:53 INFO - PROCESS | 1991 | 20:58:54 INFO - TEST-INFO | 1991: exit 0 20:58:54 INFO - TEST-OK | dromaeo_css | took 1119291ms 20:58:54 INFO - TEST-START | kraken 20:58:54 INFO - operating with platform_type : mac_ 20:58:54 INFO - Initialising browser for kraken test... 20:58:54 INFO - TEST-INFO | started process 1993 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpHcpNbi/profile http://localhost:49697/getInfo.html) 20:58:56 INFO - PROCESS | 1993 | __metrics Screen width/height:1600/1200 20:58:56 INFO - PROCESS | 1993 | colorDepth:24 20:58:56 INFO - PROCESS | 1993 | Browser inner width/height: 1024/689 20:58:56 INFO - PROCESS | 1993 | __metrics 20:58:56 INFO - PROCESS | 1993 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:58:56 INFO - PROCESS | 1993 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:58:57 INFO - TEST-INFO | 1993: exit 0 20:58:57 INFO - Browser initialized. 20:58:57 INFO - Running cycle 1/1 for kraken test... 20:58:57 INFO - TEST-INFO | started process 1994 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpHcpNbi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:59:09 INFO - PROCESS | 1994 | RSS: Main: 231866368 20:59:09 INFO - PROCESS | 1994 | 20:59:12 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49697/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49697/tests/kraken/driver.html?testName=audio-beat-detection) 20:59:13 INFO - PROCESS | 1994 | RSS: Main: 290660352 20:59:13 INFO - PROCESS | 1994 | 20:59:15 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49697/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49697/tests/kraken/driver.html?testName=audio-dft) 20:59:16 INFO - PROCESS | 1994 | RSS: Main: 291770368 20:59:16 INFO - PROCESS | 1994 | 20:59:19 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49697/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49697/tests/kraken/driver.html?testName=audio-fft) 20:59:20 INFO - PROCESS | 1994 | RSS: Main: 385785856 20:59:20 INFO - PROCESS | 1994 | 20:59:22 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49697/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49697/tests/kraken/driver.html?testName=audio-oscillator) 20:59:23 INFO - PROCESS | 1994 | RSS: Main: 286474240 20:59:23 INFO - PROCESS | 1994 | 20:59:25 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49697/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49697/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:59:25 INFO - PROCESS | 1994 | RSS: Main: 291790848 20:59:25 INFO - PROCESS | 1994 | 20:59:28 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49697/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49697/tests/kraken/driver.html?testName=imaging-darkroom) 20:59:29 INFO - PROCESS | 1994 | RSS: Main: 331128832 20:59:29 INFO - PROCESS | 1994 | 20:59:31 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49697/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49697/tests/kraken/driver.html?testName=imaging-desaturate) 20:59:32 INFO - PROCESS | 1994 | RSS: Main: 332570624 20:59:32 INFO - PROCESS | 1994 | 20:59:35 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49697/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49697/tests/kraken/driver.html?testName=json-parse-financial) 20:59:36 INFO - PROCESS | 1994 | RSS: Main: 327200768 20:59:36 INFO - PROCESS | 1994 | 20:59:37 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49697/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49697/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:59:38 INFO - PROCESS | 1994 | RSS: Main: 303050752 20:59:38 INFO - PROCESS | 1994 | 20:59:40 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49697/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49697/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:59:40 INFO - PROCESS | 1994 | RSS: Main: 305577984 20:59:40 INFO - PROCESS | 1994 | 20:59:42 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49697/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49697/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:59:43 INFO - PROCESS | 1994 | RSS: Main: 310202368 20:59:43 INFO - PROCESS | 1994 | 20:59:46 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49697/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49697/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:59:47 INFO - PROCESS | 1994 | RSS: Main: 324100096 20:59:47 INFO - PROCESS | 1994 | 20:59:50 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49697/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49697/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:59:50 INFO - PROCESS | 1994 | RSS: Main: 317517824 20:59:50 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49697/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49697/tests/kraken/driver.html?testName=ai-astar) 20:59:52 INFO - PROCESS | 1994 | RSS: Main: 361820160 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | __start_tp_report 20:59:52 INFO - PROCESS | 1994 | _x_x_mozilla_page_load 20:59:52 INFO - PROCESS | 1994 | _x_x_mozilla_page_load_details 20:59:52 INFO - PROCESS | 1994 | |i|pagename|runs| 20:59:52 INFO - PROCESS | 1994 | |0;ai-astar;103;115;115;114;114;113;115;115;114;114 20:59:52 INFO - PROCESS | 1994 | |1;audio-beat-detection;174;137;114;148;115;113;112;109;143;112 20:59:52 INFO - PROCESS | 1994 | |2;audio-dft;183;185;182;185;184;184;183;186;183;184 20:59:52 INFO - PROCESS | 1994 | |3;audio-fft;78;61;58;89;60;59;59;58;83;75 20:59:52 INFO - PROCESS | 1994 | |4;audio-oscillator;88;71;89;68;67;68;92;67;68;89 20:59:52 INFO - PROCESS | 1994 | |5;imaging-gaussian-blur;89;86;89;86;86;86;90;86;89;87 20:59:52 INFO - PROCESS | 1994 | |6;imaging-darkroom;94;94;94;96;93;93;94;96;94;101 20:59:52 INFO - PROCESS | 1994 | |7;imaging-desaturate;124;123;90;79;84;82;87;86;84;80 20:59:52 INFO - PROCESS | 1994 | |8;json-parse-financial;55;56;56;55;54;57;58;57;55;56 20:59:52 INFO - PROCESS | 1994 | |9;json-stringify-tinderbox;63;73;80;73;71;69;68;70;69;71 20:59:52 INFO - PROCESS | 1994 | |10;stanford-crypto-aes;88;87;89;87;86;86;90;88;91;98 20:59:52 INFO - PROCESS | 1994 | |11;stanford-crypto-ccm;151;152;153;149;144;145;145;146;185;146 20:59:52 INFO - PROCESS | 1994 | |12;stanford-crypto-pbkdf2;172;173;173;177;221;211;171;169;174;166 20:59:52 INFO - PROCESS | 1994 | |13;stanford-crypto-sha256-iterative;68;68;67;69;67;68;70;68;68;68 20:59:52 INFO - PROCESS | 1994 | __end_tp_report 20:59:52 INFO - PROCESS | 1994 | __start_cc_report 20:59:52 INFO - PROCESS | 1994 | _x_x_mozilla_cycle_collect,2054 20:59:52 INFO - PROCESS | 1994 | __end_cc_report 20:59:52 INFO - PROCESS | 1994 | __startTimestamp1476676792851__endTimestamp 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | ------- Summary: start ------- 20:59:52 INFO - PROCESS | 1994 | Number of tests: 14 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.50 stddev:3.65 (3.2%) stddev-sans-first:0.71 20:59:52 INFO - PROCESS | 1994 | Values: 103.0 115.0 115.0 114.0 114.0 113.0 115.0 115.0 114.0 114.0 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:126.00 stddev:21.82 (17.3%) stddev-sans-first:15.42 20:59:52 INFO - PROCESS | 1994 | Values: 174.0 137.0 114.0 148.0 115.0 113.0 112.0 109.0 143.0 112.0 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.20 (0.7%) stddev-sans-first:1.22 20:59:52 INFO - PROCESS | 1994 | Values: 183.0 185.0 182.0 185.0 184.0 184.0 183.0 186.0 183.0 184.0 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | [#3] audio-fft Cycles:10 Average:68.00 Median:68.00 stddev:11.97 (17.6%) stddev-sans-first:12.14 20:59:52 INFO - PROCESS | 1994 | Values: 78.0 61.0 58.0 89.0 60.0 59.0 59.0 58.0 83.0 75.0 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | [#4] audio-oscillator Cycles:10 Average:76.70 Median:79.50 stddev:11.12 (14.0%) stddev-sans-first:11.01 20:59:52 INFO - PROCESS | 1994 | Values: 88.0 71.0 89.0 68.0 67.0 68.0 92.0 67.0 68.0 89.0 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.00 stddev:1.65 (1.9%) stddev-sans-first:1.64 20:59:52 INFO - PROCESS | 1994 | Values: 89.0 86.0 89.0 86.0 86.0 86.0 90.0 86.0 89.0 87.0 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | [#6] imaging-darkroom Cycles:10 Average:94.90 Median:94.00 stddev:2.38 (2.5%) stddev-sans-first:2.50 20:59:52 INFO - PROCESS | 1994 | Values: 94.0 94.0 94.0 96.0 93.0 93.0 94.0 96.0 94.0 101.0 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | [#7] imaging-desaturate Cycles:10 Average:91.90 Median:84.00 stddev:16.97 (20.2%) stddev-sans-first:13.44 20:59:52 INFO - PROCESS | 1994 | Values: 124.0 123.0 90.0 79.0 84.0 82.0 87.0 86.0 84.0 80.0 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:1.20 (2.1%) stddev-sans-first:1.22 20:59:52 INFO - PROCESS | 1994 | Values: 55.0 56.0 56.0 55.0 54.0 57.0 58.0 57.0 55.0 56.0 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.00 stddev:4.35 (6.1%) stddev-sans-first:3.61 20:59:52 INFO - PROCESS | 1994 | Values: 63.0 73.0 80.0 73.0 71.0 69.0 68.0 70.0 69.0 71.0 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:88.50 stddev:3.56 (4.0%) stddev-sans-first:3.76 20:59:52 INFO - PROCESS | 1994 | Values: 88.0 87.0 89.0 87.0 86.0 86.0 90.0 88.0 91.0 98.0 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | [#11] stanford-crypto-ccm Cycles:10 Average:151.60 Median:150.00 stddev:12.17 (8.1%) stddev-sans-first:12.90 20:59:52 INFO - PROCESS | 1994 | Values: 151.0 152.0 153.0 149.0 144.0 145.0 145.0 146.0 185.0 146.0 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.70 Median:173.50 stddev:18.98 (10.9%) stddev-sans-first:19.87 20:59:52 INFO - PROCESS | 1994 | Values: 172.0 173.0 173.0 177.0 221.0 211.0 171.0 169.0 174.0 166.0 20:59:52 INFO - PROCESS | 1994 | 20:59:52 INFO - PROCESS | 1994 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 20:59:52 INFO - PROCESS | 1994 | Values: 68.0 68.0 67.0 69.0 67.0 68.0 70.0 68.0 68.0 68.0 20:59:52 INFO - PROCESS | 1994 | -------- Summary: end -------- 20:59:52 INFO - PROCESS | 1994 | 20:59:53 INFO - TEST-INFO | 1994: exit 0 20:59:53 INFO - TEST-OK | kraken | took 59457ms 20:59:53 INFO - SUITE-END | took 1178s 20:59:54 INFO - Completed test suite (00:19:39) 20:59:54 INFO - javascript benchmark 20:59:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3238.1418923985148, 3257.0, 3271.50927360896, 3271.9672803271974, 3316.8009919404835, 4650.372199753032, 5470.863228419292, 5585.916211256835, 5611.270534830472, 5630.1554766785, 5570.6657600543995, 5580.246666699994, 5589.692566908819, 5594.272744543204, 5597.496225339719, 5064.3163172971545, 5106.923396149061, 5122.871928201806, 5142.305788718531, 5197.662151960129, 5129.281900533928, 5180.2488639147305, 5192.974035129838, 5198.610104242178, 5215.191645294985, 5111.335526381584, 5139.691618502883, 5173.689578625294, 5173.896522069556, 5195.948040519603, 5216.843494695166, 5232.529072383466, 5249.86875328116, 5262.079136151158, 5308.018016666929, 4983.72589507577, 5021.648484606079, 5167.741612919339, 5189.896202075937, 5202.713850738208, 5539.418361072069, 5542.334919809648, 5550.91673624896, 5576.776928922838, 5592.077307244341, 6073.1497590337385, 6106.816795496142, 6134.723937422782, 6139.693015349259, 6142.477889379408, 6103.115048317969, 6108.694565271719, 6114.71942267015, 6175.197224360849, 6184.165137706397, 5672.801451949162, 5689.829305120853, 5693.4875861172695, 5720.399358067343, 5759.65442073477, 6210.5652604317265, 6213.130161777355, 6219.315875253718, 6226.283977342573, 6241.188645476059, 5908.734106965197, 6043.939560604338, 6062.333143354227, 6076.635401875901, 6077.544184186204, 6084.30030546484, 6112.052631842072, 6121.574489287494, 6138.633639632459, 6139.185504090456, 5570.6657600544095, 5718.456746609066, 6095.455447509409, 6135.294441139237, 6179.62922224668, 6106.755729770759, 6158.753649854045, 6163.691815409212, 6170.197874276315, 6180.66006369645, 4875.609951203895, 4880.6583539152625, 4888.755562221874, 4890.315355850184, 4919.163742163841, 4304.289792184317, 4326.048269380732, 4339.262325404688, 4349.347597860283, 4355.412019377407, 5567.387587365387, 5571.13647384656, 5585.748641311151, 5593.412691667398, 5641.80253691127, 6131.724072416663, 6158.876822463526, 6188.1027251048345, 6189.473894718999, 6191.690415479208, 5430.239766432703, 5486.204500347428, 5494.5054945055135, 5590.105583106683, 5604.0, 4530.275155975094, 5268.104422248227, 5305.389880163831, 5315.176147697113, 5371.221172929903, 6186.0, 6190.876182476415, 6190.969045154746, 6195.473384762256, 6209.379062093754, 5979.342272349951, 6160.630362178284, 6181.629102253879, 6199.814005579839, 6200.627962322275, 6118.942928706938, 6150.200473938359, 6202.627842329474, 6205.441510264098, 6207.65857877803, 6041.708043694141, 6108.311716707834, 6112.1995365628145, 6116.792316687851, 6131.804871721949, 4982.950170498249, 6191.659458729813, 6198.1012753149635, 6201.3178550358525, 6201.875962480815, 5802.477776999922, 5864.354920958776, 5870.354261031369, 5913.438223368773, 5981.461668449861, 6004.249468816398, 6089.238845144357, 6114.357992410733, 6116.204893363834, 6121.265448146251, 5896.321922978912, 5900.763969441175, 5902.586818922634, 5917.260342457193, 5925.911111333247, 5968.373320801427, 5980.790672326448, 6032.819015429544, 6035.486983606443, 6052.092186172109, 5679.176519404746, 5683.175939488834, 5704.344000440003, 5732.34078081026, 5816.592838501433], "value": 5593.384341636469, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.93655856459367, 475.81443237137523, 508.65207200917587, 509.71710700561187, 509.9698608810202, 24828.0, 34639.826800865994, 40662.79668601657, 41933.79033104838, 42183.78908105463, 42651.78674106633, 43133.568664313345, 43141.56858431415, 43216.351754723706, 43281.567184328145, 9453.763655908606, 9599.856002159973, 9602.231821454267, 9604.135627793497, 9617.182539484153, 9479.24166066713, 9596.376235544667, 9605.423674579513, 9608.279379046564, 9622.470764107971, 9592.280578956606, 9637.951810240938, 9647.469389183592, 9649.276304277173, 9670.0, 9550.235981121528, 9602.327837051409, 9606.13544780966, 9609.807803843954, 9625.470599117045, 9398.765030874214, 9478.478683672396, 9490.525473726355, 9497.0, 9516.714498565054, 40391.99020024494, 40675.1864962702, 40772.38841417381, 40828.0, 40844.1831163378, 3361.1261072121424, 3399.915002124942, 3444.0528854564946, 3700.2229531798353, 3949.545802232751, 30218.395632087235, 31466.89865854707, 31507.684923150704, 31578.368432631218, 32021.039368818972, 3141.3246152077186, 3241.173500757292, 3267.77125601208, 3282.786618869766, 3337.7830441021256, 464602.0, 466123.0, 466928.66535667446, 466986.0, 471118.0, 59421.405785941584, 59876.70061649664, 59885.10172347418, 59991.0, 60037.0, 63435.0, 63457.682711586145, 63469.68265158737, 63557.36442635607, 63646.36353636497, 15987.0, 16178.514644560682, 16201.837981620269, 16229.026258424532, 16237.67524649524, 12531.498740050296, 12572.371381431074, 12584.244945303311, 12613.369331533386, 12635.620931372074, 13618.795718064237, 14143.504977325743, 16577.17114144288, 16617.0860602668, 16773.72270071124, 5723.45627165427, 6820.420264277493, 6977.406920411822, 7062.788116356517, 7123.643817809192, 745.8545583611144, 971.4990339924833, 1054.330500132406, 1060.5015642647943, 1066.6586692258559, 28411.289717757223, 29902.850485747433, 30057.549136762966, 30102.24744381408, 30328.090157295315, 979.1921664626711, 1031.6904928521415, 1112.5883423133491, 1127.1940562497853, 1149.7872893514727, 15127.54617361481, 15621.921890390475, 15652.843471565366, 15806.419358064102, 15819.446319378996, 595828.0, 598142.0, 598466.0, 600149.0, 603205.0, 58649.82700345969, 58828.41171588315, 58857.70571147117, 58876.70561647164, 58876.70561647164, 1242.726600147966, 1285.916415432994, 1346.8181795457488, 1381.247220264958, 1409.5348534983432, 28819.855900720362, 29644.66599003007, 31334.993487471238, 31437.52843707392, 32123.35753284968, 50075.248871267686, 50660.0, 50792.746036269586, 50794.492055078976, 50797.23804142867, 423082.88381068164, 425724.0, 426145.0, 426786.0, 427027.0, 23553.41116472102, 25724.09965651193, 26678.0, 26696.46607067809, 27602.033928812394, 47104.29343559918, 47148.292775607704, 47548.049039019716, 48273.0, 48312.275315871, 36116.63883361133, 38905.6109438902, 39361.40957885577, 39453.0, 40115.59884401118, 38935.61064389433, 39017.60982390253, 40252.0, 40350.9912252196, 40597.1880562393, 37407.625923740416, 37676.43485347667, 38233.23533529369, 38542.22915541617, 38932.221355573296, 26425.207243782716, 29859.402811943204, 33137.834310829254, 34248.65751342554, 34611.4808277871, 23194.072237110326, 25399.238022859343, 25629.949604032005, 26107.0, 26316.947102166992], "value": 19579.42486239689, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.115189721901, 361.0576395607462, 368.9299033183695, 379.86135060702844, 397.8110397561157, 14704.705905881889, 15021.24893755312, 15712.607184820385, 19910.0, 20319.593608127827, 19724.211031558756, 20585.07367168477, 20779.792202077973, 20800.959952002377, 21013.94930253485, 5168.741562921858, 5719.399463056381, 5994.0, 6009.909851352233, 6014.609050411731, 5249.685018898878, 5914.9112763308585, 5974.3428222895445, 5976.193213916109, 5982.491488223506, 5650.632708873931, 5897.233359663238, 5949.077892926603, 5950.9107363389585, 5953.3748956359605, 5873.70631468427, 5927.8221653350465, 5931.555133365015, 5931.792387266425, 5941.584089113764, 5098.949010509885, 5159.458256883011, 5166.405863325728, 5355.839324820262, 5424.755885985141, 12836.358182090858, 13090.86909130906, 13098.47606095755, 14194.57416277503, 24407.389815254584, 1843.216632930999, 2000.603843812334, 2042.8774273543636, 2207.834412419075, 2824.7457728804507, 9681.273904457123, 9755.268354873413, 9804.264680149017, 10161.59353625854, 10208.540615672313, 1715.6139868529606, 1760.9735853962202, 1771.9645607087787, 1795.4075155198752, 1901.2300018492533, 114870.0, 124691.0, 125232.37383813114, 126341.36829315887, 129866.35066824606, 10405.323653962469, 10730.409827459565, 11298.435078246055, 11378.772424551462, 11422.0, 14454.638634034232, 14458.349374278183, 14489.420423183166, 14516.854831451761, 14521.636959076108, 8029.478083924526, 8408.579571021424, 8408.915910840935, 8500.97988241415, 8546.034412085131, 6897.827554311182, 8288.544130072903, 8304.87884135634, 8339.041010283894, 8450.87323690145, 4459.572936660237, 4465.732056076646, 4469.374287599739, 4479.619232365286, 4566.200914839957, 1641.8850680452254, 1864.683003889315, 1909.6562618728485, 1919.0692514130635, 1924.7978962209047, 884.5090974509209, 972.8248915195333, 979.5983646704817, 998.5706146356991, 1009.2733232072895, 19796.0, 19952.3016694415, 21549.0, 21618.351449456542, 21808.563828723654, 845.222395396237, 871.8212766382876, 928.3176865004217, 947.4930911962067, 1019.8521214423866, 9536.879928136457, 10949.83575246372, 11143.33140011602, 11203.358908354654, 11340.149488788375, 576886.8136757473, 577334.0, 583427.0, 594814.0, 595067.0, 99486.0, 123496.38251808683, 129236.0, 129863.35068324598, 133790.0, 1024.5440778853338, 1028.9331193472551, 1056.6671498477954, 1057.3867157048894, 1060.7242117049623, 17826.643467130845, 18345.54136146607, 18370.805897616614, 18459.0, 18758.59310551687, 51598.74200628973, 51994.70013249699, 52710.0, 53491.19763203633, 53578.0, 72084.0, 75017.24982750103, 77062.6146869262, 78758.0, 86852.56573717344, 7030.0, 7280.0535930328715, 7289.5261807982315, 7342.6695798688115, 7408.073990751156, 9826.508674566387, 11724.70688232767, 12399.814002790146, 12874.420651070537, 13241.602751917459, 10140.391576505433, 11251.437428128724, 11455.942720286344, 11468.31190128595, 11654.30074195551, 11183.664490065312, 11200.943995279971, 11474.368909709712, 11474.655760327203, 11794.174407791716, 11499.195056345976, 11719.824202637137, 11742.941285293518, 11890.583829566269, 11949.342786146846, 7199.208087110309, 7235.2764723527225, 7349.118105827335, 7562.848743024999, 7565.0, 10224.795504089729, 10257.538410771682, 11024.393658348868, 11477.85221477879, 11623.476943537393], "value": 9274.23545691781, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1380.6203294094116, 1554.5491807375865, 1579.928903199356, 1813.5103522049046, 1959.5590992026796, 7866.528008319505, 8319.209675080858, 8319.417640765148, 8323.708670196536, 8352.498850069, 8097.2307630774985, 8538.231559159674, 8694.913050869489, 8807.647694092244, 8873.86689199661, 2267.036509483471, 2297.8506397084134, 2298.977010229893, 2303.9654405183937, 2304.83866129371, 2328.092044102801, 2367.065009321317, 2371.8932648030795, 2374.9406264843433, 2376.988115059431, 2337.8947947342413, 2363.078399424226, 2365.290412876139, 2382.1543352109925, 2390.5816482115647, 2299.597570425169, 2324.1749179041367, 2339.742628310893, 2341.1454818991115, 2343.086196383395, 2047.6314263432575, 2327.7090363704538, 2328.2549584133085, 2329.196427232619, 2330.9650355244685, 8761.386702930797, 8831.116888311182, 8832.470051796912, 8845.823083538293, 8858.247049000842, 5125.564327032207, 5134.255532947702, 5309.256704061435, 5309.893802123975, 5335.706536140511, 3426.880059197916, 3580.1407662160896, 3660.5973342932257, 3667.6332366763377, 3674.430463278196, 1050.1441325319915, 1056.6988408303673, 1070.4968664727644, 1072.0833687197412, 1077.784443111373, 11211.495482703298, 11483.77032459346, 11546.36494992775, 11573.016293615054, 11648.94175529117, 4381.824727010884, 4490.124425737015, 4582.404287442611, 4608.861734147981, 4631.95368046322, 9022.729318120468, 9032.23226025782, 9057.592408341641, 9057.81884362309, 9070.682526111554, 5191.065608190486, 5191.117510023305, 5195.792168313225, 5200.76596553156, 5200.985807767524, 4117.1765646870745, 4359.0, 4521.683482156283, 4522.140793249272, 4880.829170978998, 3656.0494271489074, 3692.0, 3761.1161377076915, 3761.22894806564, 3762.8682996095554, 1133.7619099989095, 1139.897409233173, 1149.5918948773233, 1152.3546813784308, 1153.8038533449335, 761.5278527313102, 856.5289090999925, 891.3671293381642, 910.7040211931148, 943.6225509796, 5341.664583854036, 5607.4112218217315, 5793.188953546507, 5902.439268269508, 5947.31605865331, 879.388824766781, 908.496438394445, 923.8752768376318, 968.5931908598405, 993.101243374747, 3940.054386947112, 3951.78265195417, 3957.703172262092, 4034.677225821927, 4083.0813067059676, 10490.737731556772, 10730.946345268223, 10842.891571084188, 10843.620473283398, 10896.564137434414, 10817.945910270399, 10827.296225745302, 10829.29609575375, 10834.295770774874, 10845.620403286004, 7768.145503994556, 8162.918370816335, 8192.139825318374, 8192.877106843402, 8225.917740822635, 5883.382244864399, 5889.263842019747, 5951.940480595312, 6088.4215999479975, 6136.171616831849, 9920.255980801325, 9957.850632240377, 10000.24995625777, 10017.198624109908, 10129.696109116738, 9273.165415112673, 9769.462679552693, 10159.949200253952, 10241.539130738985, 10261.846072308772, 7013.894791578028, 7203.819904502429, 7338.486305958531, 7467.813304667427, 7558.659860306189, 8181.631826567937, 8200.26197642215, 8205.507669539847, 8268.710595129141, 8304.6678132874, 7453.776386708408, 7506.211847755907, 7566.848663026819, 7569.810754731176, 7570.9242907570215, 7250.709971601077, 7328.487005909536, 7371.520851144658, 7372.815679608053, 7458.701651933862, 6743.494237932273, 6840.9657951711915, 6921.723131074902, 6926.376626103674, 6931.896021559782, 4851.223804191312, 4932.802687892444, 4938.9259161111895, 5004.1492946198505, 5163.199704045879, 4945.554900059012, 5010.899782004412, 5064.518870707277, 5083.491650834887, 5191.636585438983], "value": 4526.315115003046, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [259.2016588906169, 294.77891581314015, 300.42168825011845, 307.14613374818, 309.81565968248884, 4858.764968114662, 5093.668911520753, 5096.413912400075, 5122.41092274389, 5143.6656617319895, 4697.436307643079, 5015.648904576681, 5077.898442031166, 5109.6678715883445, 5128.179491281396, 3992.2852460326967, 3997.5003124609425, 4010.6991975601873, 4021.8227600195955, 4040.9595904040875, 3884.9805750971204, 4000.6999475039343, 4034.616711412426, 4055.0, 4061.2689715851134, 3915.060385507486, 3948.7038472114564, 3986.5614782373914, 3994.760314381132, 4023.6982226333, 3409.4885767135006, 3644.562652481719, 3705.1478160023075, 3906.765594064351, 3937.88186354407, 2917.9854100729362, 3197.2006998250436, 3388.949165762491, 3405.557277553902, 3465.7227421806197, 4160.9375859362135, 4353.5428779977965, 4987.351644286219, 5025.522575355372, 5098.541131298164, 4446.888827779299, 4449.755263460508, 4453.421055262795, 4465.218586747339, 4498.86304005598, 4978.6514943953935, 5325.680459172462, 5398.676079435208, 5445.529706979093, 5488.313960754906, 3673.971288039353, 3689.151495156129, 3698.778073315583, 3701.0932321581117, 3703.740738148331, 4975.552200301954, 5169.353830771153, 5318.6542874713405, 5656.462636049569, 5702.828915132512, 3615.349237137288, 3740.2893450244364, 3745.9250814984093, 3753.9249215016093, 3757.304898593769, 3722.553293604785, 3775.0562359410146, 3792.932851595887, 3824.598417166213, 3841.519810023747, 4434.889127771767, 4459.777011149495, 4462.742198195336, 4478.126765280739, 4497.730136191839, 4774.904501910012, 4916.410030796327, 5402.6758394496455, 5438.91841622376, 5462.317210348707, 1152.757920836617, 1221.9266843989212, 1280.7566562353122, 1283.6084994076896, 1298.5519995601503, 692.3768608252512, 753.2354660020084, 775.9573223472763, 789.4197764642983, 807.8667019941761, 4027.965877231979, 4097.221527909687, 4211.936820947688, 4323.546027667113, 4334.5882141196535, 3960.4257382679352, 4285.528591854893, 4490.236659767847, 4507.3689683444345, 4657.626000329843, 3732.533433320835, 3818.7517811342173, 4118.732282401635, 4158.334666453353, 4245.957540424618, 2325.6627788970504, 2328.569214695287, 2357.481354102095, 2453.178844329036, 2945.3078526546246, 5365.9950427759795, 5681.914771278434, 5847.26909136358, 5857.677827719431, 5864.953182304196, 5099.796008159707, 5706.9429305707245, 5734.369219385864, 5748.913766293509, 5769.423057694281, 4660.460049273086, 4821.5178482152205, 5736.250443614737, 5864.266966629171, 5916.319623243382, 4764.595009424099, 5285.577153827608, 5644.379118296902, 5645.717714114196, 5655.632383895034, 4609.792559334771, 5281.339832520935, 5600.27196464457, 5638.351589567253, 5653.957420444402, 4919.803207871645, 5833.212516310244, 5880.794172203952, 5887.555779868825, 5898.790747896756, 3232.995542763502, 3751.343514884939, 3813.1039205786064, 3816.31306364857, 3837.4819399381845, 4852.175130227799, 5346.465353464622, 5359.464053594609, 5678.971605142113, 5699.487046165865, 4361.912761744811, 4899.167141585867, 5175.534201921694, 5361.276227709365, 5413.891722165614, 4665.6034237090225, 4964.478729733326, 5002.074616196015, 5358.705271210121, 5384.784608615768, 4883.6093112551625, 5200.6619569727845, 5370.62405631602, 5405.891882162414, 5452.410105200157, 4990.326305948651, 5204.115300399017, 5268.0, 5331.466853314638, 5461.317335333083, 4485.932711009272, 4579.656525760514, 5236.764345604381, 5346.8395948121615, 5374.435684253097], "value": 3873.834649216205, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.7313445499781, 742.5433358484531, 753.935915447187, 827.7847759582514, 841.4740787008118, 15090.622734431645, 15854.28655710493, 15862.84137158628, 15919.840801591981, 16206.027638341708, 14304.856951430482, 14394.568162955102, 14414.927925360358, 14424.0, 14432.639184020378, 699.3775539769609, 717.8456631824157, 720.3336913355151, 721.239092757141, 865.0441262405046, 716.6022857314189, 721.3579913876654, 869.9608517616691, 899.1143723432414, 908.2688435809171, 939.3096074385321, 946.1012038563358, 950.0, 950.0546955779009, 953.752024473635, 942.3121121581285, 942.641796117471, 944.5796620503844, 945.026622578745, 948.5731420860606, 705.1432509500981, 706.2443185791205, 707.6815433055145, 707.7841258416181, 708.3624737736079, 11813.173077884552, 12283.955863751591, 12289.139760216789, 12394.81407778884, 12418.0, 12446.128770986035, 14402.495912643111, 14496.927515362355, 14718.675319221322, 14872.702545949129, 10193.337433066827, 11452.828207576893, 11680.41597920109, 11684.70788230301, 11941.402929853559, 3803.3724435468116, 3831.8467261309793, 3842.5581058178386, 3961.3463778476516, 3966.3852102924093, 107005.0, 108139.45930270376, 108315.0, 108936.4553177237, 109882.0, 11098.944505277583, 11148.777024459465, 11161.0, 11527.48126334317, 11804.704882377837, 22765.544689106126, 22797.772022279896, 22829.885850570972, 22833.88583057074, 22946.65580016301, 6946.895796563055, 7620.9618951905995, 7852.450328476952, 8028.959855200686, 8034.879476807852, 4128.855490057834, 4184.330507118907, 4237.427947227085, 4243.215005224043, 4257.63810076147, 3505.9298814023578, 3651.9817400912825, 3654.6710796028483, 3655.409896694893, 3671.632836716307, 619.4332186049804, 619.8946179149556, 638.575347393987, 640.1549954060596, 640.7212862404868, 639.469240530368, 639.5139693832627, 655.9147310849654, 675.7938828657209, 681.7954613615995, 8510.191531804468, 8992.8651070234, 9189.356745027917, 9304.0, 9435.669751558662, 649.7043845050491, 654.5581732330658, 667.5393978155057, 670.9329067093252, 682.7917485166972, 4324.308110702273, 4618.792154353062, 4624.352590637313, 4630.21286381316, 4665.953340466552, 95081.52459237659, 146253.0, 156569.0, 163015.0, 163666.0, 134670.0, 136448.31775841257, 138791.0, 138973.30513347368, 142063.0, 75587.0, 105622.0, 110725.44637276762, 116645.25289086204, 117712.0, 9329.58016889228, 9340.579673914826, 9631.614735410505, 9747.512624368896, 9843.621892934852, 44537.33194002158, 51152.976940461725, 53561.464385357205, 53576.46423535715, 56608.0, 62143.367777994485, 62806.0, 62808.37191628208, 76938.2306176931, 82689.0, 11463.656090317303, 13190.010749193658, 13418.597442076752, 13561.118527295694, 13946.651333716738, 13598.320083995563, 13610.659733506345, 14748.007430934244, 14907.701845963236, 15080.849191508383, 13955.860441395456, 14026.649333766738, 14037.719245615235, 14213.786793198316, 14794.852051479347, 13859.099158555066, 13879.722405552035, 13896.86103138996, 13977.58067257984, 14436.061655992327, 13729.244891531062, 13746.587602371945, 13789.655258618615, 14384.712305753617, 14469.421223150955, 10573.790529787044, 11151.219414640897, 11454.0, 11689.240199387013, 11910.166288359731, 9492.620295188115, 10578.206634502289, 10690.037896589345, 10737.731556711145, 11446.828297575377], "value": 7726.609683974417, "unit": "score"}], "name": "dromaeo_css", "value": 7185.31756822728, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 115.0, 114.0, 114.0, 113.0, 115.0, 115.0, 114.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 137.0, 114.0, 148.0, 115.0, 113.0, 112.0, 109.0, 143.0, 112.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 182.0, 185.0, 184.0, 184.0, 183.0, 186.0, 183.0, 184.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 61.0, 58.0, 89.0, 60.0, 59.0, 59.0, 58.0, 83.0, 75.0], "value": 68.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 89.0, 68.0, 67.0, 68.0, 92.0, 67.0, 68.0, 89.0], "value": 76.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 86.0, 86.0, 90.0, 86.0, 89.0, 87.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 96.0, 93.0, 93.0, 94.0, 96.0, 94.0, 101.0], "value": 94.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 123.0, 90.0, 79.0, 84.0, 82.0, 87.0, 86.0, 84.0, 80.0], "value": 91.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 55.0, 54.0, 57.0, 58.0, 57.0, 55.0, 56.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 73.0, 80.0, 73.0, 71.0, 69.0, 68.0, 70.0, 69.0, 71.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 87.0, 86.0, 86.0, 90.0, 88.0, 91.0, 98.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 153.0, 149.0, 144.0, 145.0, 145.0, 146.0, 185.0, 146.0], "value": 151.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 173.0, 177.0, 221.0, 211.0, 171.0, 169.0, 174.0, 166.0], "value": 180.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 69.0, 67.0, 68.0, 70.0, 68.0, 68.0, 68.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1459.6999999999998, "alertThreshold": 2.0}]} 20:59:54 INFO - Return code: 0 20:59:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:59:54 INFO - # TBPL SUCCESS # 20:59:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:59:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:59:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:59:54 INFO - 2016-10-16 20:59:54.212 screenresolution[1998:22510] starting screenresolution argv=screenresolution get 20:59:54 INFO - 2016-10-16 20:59:54.215 screenresolution[1998:22510] Display 0: 1600x1200x32@60 20:59:54 INFO - 2016-10-16 20:59:54.221 screenresolution[1999:22513] starting screenresolution argv=screenresolution list 20:59:54 INFO - Available Modes on Display 0 20:59:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:59:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:59:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:59:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:59:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:59:54 INFO - Intel Iris: 20:59:54 INFO - Chipset Model: Intel Iris 20:59:54 INFO - Type: GPU 20:59:54 INFO - Bus: Built-In 20:59:54 INFO - VRAM (Dynamic, Max): 1536 MB 20:59:54 INFO - Vendor: Intel (0x8086) 20:59:54 INFO - Device ID: 0x0a2e 20:59:54 INFO - Revision ID: 0x0009 20:59:54 INFO - Displays: 20:59:54 INFO - Display: 20:59:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:59:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:59:54 INFO - Display Serial Number: 1600x1200 60 20:59:54 INFO - Main Display: Yes 20:59:54 INFO - Mirror: Off 20:59:54 INFO - Online: Yes 20:59:54 INFO - Rotation: Supported 20:59:54 INFO - Return code: 0 20:59:54 INFO - Running post-action listener: _resource_record_post_action 20:59:54 INFO - [mozharness: 2016-10-17 03:59:54.456092Z] Finished run-tests step (success) 20:59:54 INFO - Running post-run listener: _resource_record_post_run 20:59:54 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 241664; Write bytes: 706927104; Read time: 2; Write time: 2219 20:59:54 INFO - TinderboxPrint: CPU usage
53.7% 20:59:54 INFO - TinderboxPrint: I/O read bytes / time
241,664 / 2 20:59:54 INFO - TinderboxPrint: I/O write bytes / time
706,927,104 / 2,219 20:59:54 INFO - TinderboxPrint: CPU idle
2,247.4 (46.3%) 20:59:54 INFO - TinderboxPrint: CPU system
972.9 (20.0%) 20:59:54 INFO - TinderboxPrint: CPU user
1,637.4 (33.7%) 20:59:54 INFO - TinderboxPrint: Swap in / out
406,233,088 / 0 20:59:54 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 0; Write bytes: 409888768; Read time: 0; Write time: 759 20:59:54 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 241664; Write bytes: 295514624; Read time: 2; Write time: 1454 20:59:54 INFO - Running post-run listener: _upload_blobber_files 20:59:54 INFO - Blob upload gear active. 20:59:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:59:54 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 20:59:54 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:59:54 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:59:55 INFO - (blobuploader) - INFO - Open directory for files ... 20:59:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:59:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:59:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:59:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:59:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:59:56 INFO - (blobuploader) - INFO - Done attempting. 20:59:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:59:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:59:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:59:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:59:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:59:57 INFO - (blobuploader) - INFO - Done attempting. 20:59:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:59:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:59:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:59:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:59:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:59:59 INFO - (blobuploader) - INFO - Done attempting. 20:59:59 INFO - (blobuploader) - INFO - Iteration through files over. 20:59:59 INFO - Return code: 0 20:59:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:59:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:59:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/58edb41e6b2e639d291c7b586098ccd786e5120f93a149007d310993e0171c6631c97fffbb69cf0bc811e15e70e363e14b8ef56a398cfac135c047e325fbf6f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/85800783dbe83f1445240327828767d9161598f29db8b606d7130a75c2eedcbfa5e5bba75ce688623fd4715bcd75db7ae1d7efdf964073754cda0753b713772e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e9e0fe8dc63af5cf7f8f1059c74a2683f5b520a23e61aff72bf96e7d21bd4e632f9efe8f2dd9f22962d74a5835a0d6888d8d5cf53fc0d6baf36550888409c7cf"} 20:59:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:59:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:59:59 INFO - Contents: 20:59:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/58edb41e6b2e639d291c7b586098ccd786e5120f93a149007d310993e0171c6631c97fffbb69cf0bc811e15e70e363e14b8ef56a398cfac135c047e325fbf6f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/85800783dbe83f1445240327828767d9161598f29db8b606d7130a75c2eedcbfa5e5bba75ce688623fd4715bcd75db7ae1d7efdf964073754cda0753b713772e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e9e0fe8dc63af5cf7f8f1059c74a2683f5b520a23e61aff72bf96e7d21bd4e632f9efe8f2dd9f22962d74a5835a0d6888d8d5cf53fc0d6baf36550888409c7cf"} 20:59:59 INFO - Running post-run listener: copy_logs_to_upload_dir 20:59:59 INFO - Copying logs to upload dir... 20:59:59 INFO - mkdir: /builds/slave/test/build/upload/logs 20:59:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.799977 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-16 20:59:59.947526) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-16 20:59:59.951057) ========= 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.nIGLod8ueD/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.isQEDwjUKk/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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/Autoland/sha512/58edb41e6b2e639d291c7b586098ccd786e5120f93a149007d310993e0171c6631c97fffbb69cf0bc811e15e70e363e14b8ef56a398cfac135c047e325fbf6f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/85800783dbe83f1445240327828767d9161598f29db8b606d7130a75c2eedcbfa5e5bba75ce688623fd4715bcd75db7ae1d7efdf964073754cda0753b713772e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e9e0fe8dc63af5cf7f8f1059c74a2683f5b520a23e61aff72bf96e7d21bd4e632f9efe8f2dd9f22962d74a5835a0d6888d8d5cf53fc0d6baf36550888409c7cf"} build_url:https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011411 build_url: 'https://queue.taskcluster.net/v1/task/fXdTmOyLQc-o6qc57k0NKQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/58edb41e6b2e639d291c7b586098ccd786e5120f93a149007d310993e0171c6631c97fffbb69cf0bc811e15e70e363e14b8ef56a398cfac135c047e325fbf6f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/85800783dbe83f1445240327828767d9161598f29db8b606d7130a75c2eedcbfa5e5bba75ce688623fd4715bcd75db7ae1d7efdf964073754cda0753b713772e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e9e0fe8dc63af5cf7f8f1059c74a2683f5b520a23e61aff72bf96e7d21bd4e632f9efe8f2dd9f22962d74a5835a0d6888d8d5cf53fc0d6baf36550888409c7cf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-16 20:59:59.983927) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 20:59:59.984258) ========= 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.nIGLod8ueD/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.isQEDwjUKk/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.005210 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 21:00:00.033391) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-16 21:00:00.033754) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-16 21:00:00.034103) ========= ========= Total master_lag: 0.18 =========