builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0092 starttime: 1476726044.03 results: success (0) buildid: 20161017082949 builduid: 6fb2a5a5dc10448f833be55598f4611a revision: 6fc7fc30b5c5f093b60095b0da5317d38771c5d4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 10:40:44.026255) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 10:40:44.026717) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 10:40:44.027004) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 10:40:44.098168) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 10:40:44.098485) ========= 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.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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-17 10:40:44-- 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% 15.6M=0.001s 2016-10-17 10:40:44 (15.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114635 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 10:40:44.235340) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 10:40:44.235652) ========= 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.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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.028565 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 10:40:44.283740) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 10:40:44.284114) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 6fc7fc30b5c5f093b60095b0da5317d38771c5d4 --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 6fc7fc30b5c5f093b60095b0da5317d38771c5d4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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-17 10:40:44,358 truncating revision to first 12 chars 2016-10-17 10:40:44,358 Setting DEBUG logging. 2016-10-17 10:40:44,358 attempt 1/10 2016-10-17 10:40:44,358 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/6fc7fc30b5c5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-17 10:40:45,112 unpacking tar archive at: autoland-6fc7fc30b5c5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.004249 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 10:40:45.311786) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 10:40:45.312278) ========= script_repo_revision: 6fc7fc30b5c5f093b60095b0da5317d38771c5d4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 10:40:45.312833) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 10:40:45.313133) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 10:40:45.331925) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-17 10:40:45.332397) ========= /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.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 10:40:45 INFO - MultiFileLogger online at 20161017 10:40:45 in /builds/slave/test 10:40: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 10:40:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:40:45 INFO - {'append_to_log': False, 10:40:45 INFO - 'base_work_dir': '/builds/slave/test', 10:40:45 INFO - 'blob_upload_branch': 'Autoland', 10:40:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:40:45 INFO - 'branch': 'Autoland', 10:40:45 INFO - 'buildbot_json_path': 'buildprops.json', 10:40:45 INFO - 'config_files': ('talos/mac_config.py',), 10:40:45 INFO - 'default_actions': ('clobber', 10:40:45 INFO - 'read-buildbot-config', 10:40:45 INFO - 'download-and-extract', 10:40:45 INFO - 'populate-webroot', 10:40:45 INFO - 'create-virtualenv', 10:40:45 INFO - 'install', 10:40:45 INFO - 'run-tests'), 10:40:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:40:45 INFO - 'download_minidump_stackwalk': True, 10:40:45 INFO - 'download_symbols': 'ondemand', 10:40:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:40:45 INFO - 'tooltool.py': '/tools/tooltool.py', 10:40:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:40:45 INFO - '/tools/misc-python/virtualenv.py')}, 10:40:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:40:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:40:45 INFO - 'installer_path': 'installer.exe', 10:40:45 INFO - 'log_level': 'info', 10:40:45 INFO - 'log_name': 'talos', 10:40:45 INFO - 'log_to_console': True, 10:40:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:40:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:40:45 INFO - 'opt_config_files': (), 10:40:45 INFO - 'pip_index': False, 10:40:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:40:45 INFO - 'cmd': ('bash', 10:40:45 INFO - '-c', 10:40:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:40:45 INFO - 'enabled': True, 10:40:45 INFO - 'halt_on_failure': False, 10:40:45 INFO - 'name': 'check_screen_resolution'},), 10:40:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:40:45 INFO - 'cmd': ('bash', 10:40:45 INFO - '-c', 10:40:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:40:45 INFO - 'enabled': True, 10:40:45 INFO - 'halt_on_failure': False, 10:40:45 INFO - 'name': 'check_screen_resolution'},), 10:40:45 INFO - 'run_cmd_checks_enabled': True, 10:40:45 INFO - 'sps_profile': False, 10:40:45 INFO - 'sps_profile_interval': 0, 10:40:45 INFO - 'suite': 'dromaeojs', 10:40:45 INFO - 'system_bits': '32', 10:40:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:40:45 INFO - 'title': 't-yosemite-r7-0092', 10:40:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:40:45 INFO - 'use_talos_json': True, 10:40:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:40:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:40:45 INFO - 'work_dir': 'build'} 10:40:45 INFO - [mozharness: 2016-10-17 17:40:45.534297Z] Running clobber step. 10:40:45 INFO - Running pre-action listener: _resource_record_pre_action 10:40:45 INFO - Running main action method: clobber 10:40:45 INFO - rmtree: /builds/slave/test/build 10:40:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:40:47 INFO - Running post-action listener: _resource_record_post_action 10:40:47 INFO - [mozharness: 2016-10-17 17:40:47.717930Z] Finished clobber step (success) 10:40:47 INFO - [mozharness: 2016-10-17 17:40:47.718188Z] Running read-buildbot-config step. 10:40:47 INFO - Running pre-action listener: _resource_record_pre_action 10:40:47 INFO - Running main action method: read_buildbot_config 10:40:47 INFO - Using buildbot properties: 10:40:47 INFO - { 10:40:47 INFO - "project": "", 10:40:47 INFO - "product": "firefox", 10:40:47 INFO - "script_repo_revision": "production", 10:40:47 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 10:40:47 INFO - "repository": "", 10:40:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 10:40:47 INFO - "buildid": "20161017082949", 10:40:47 INFO - "pgo_build": "False", 10:40:47 INFO - "basedir": "/builds/slave/test", 10:40:47 INFO - "buildnumber": 260, 10:40:47 INFO - "slavename": "t-yosemite-r7-0092", 10:40:47 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:40:47 INFO - "platform": "yosemite_r7", 10:40:47 INFO - "scheduler": "tests-autoland-macosx64-talos", 10:40:47 INFO - "branch": "autoland", 10:40:47 INFO - "repo_path": "integration/autoland", 10:40:47 INFO - "revision": "6fc7fc30b5c5f093b60095b0da5317d38771c5d4", 10:40:47 INFO - "stage_platform": "macosx64", 10:40:47 INFO - "builduid": "6fb2a5a5dc10448f833be55598f4611a", 10:40:47 INFO - "slavebuilddir": "test" 10:40:47 INFO - } 10:40:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 10:40:47 INFO - Running post-action listener: _resource_record_post_action 10:40:47 INFO - [mozharness: 2016-10-17 17:40:47.723906Z] Finished read-buildbot-config step (success) 10:40:47 INFO - [mozharness: 2016-10-17 17:40:47.724145Z] Running download-and-extract step. 10:40:47 INFO - Running pre-action listener: _resource_record_pre_action 10:40:47 INFO - Running main action method: download_and_extract 10:40:47 INFO - mkdir: /builds/slave/test/build/tests 10:40:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:47 INFO - trying https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 10:40:47 INFO - Downloading https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/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 10:40:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/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 10:40:48 INFO - Downloaded 1199 bytes. 10:40:48 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 10:40:48 INFO - Using the following test package requirements: 10:40:48 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:40:48 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:40:48 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 10:40:48 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:40:48 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 10:40:48 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:40:48 INFO - u'jsshell-mac.zip'], 10:40:48 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:40:48 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 10:40:48 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:40:48 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:40:48 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 10:40:48 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:40:48 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 10:40:48 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:40:48 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 10:40:48 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:40:48 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 10:40:48 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:40:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:40:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:40:48 INFO - Fetch https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:40:49 INFO - Http code: 200 10:40:49 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 10:40:49 INFO - x-amz-cf-id: cwsTfLkca2HfOZale6hOnoFeAkrDupRs7s8kbhYKurFRWeFH612qRg== 10:40:49 INFO - x-amz-version-id: hRtR2xU4jmdhxDCx.WMxU_U48Ob5t5Yt 10:40:50 INFO - Expected file size: 16483174 10:40:50 INFO - Obtained file size: 16483174 10:40:56 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 10:40:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:40:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:40:56 INFO - Fetch https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:40:57 INFO - Http code: 200 10:40:57 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 10:40:57 INFO - x-amz-cf-id: IySInpA6pgtsCAvSDqlmUbJN2XljLnxapMYl-TL_t2JXNLrN--vnrg== 10:40:57 INFO - x-amz-version-id: hRtR2xU4jmdhxDCx.WMxU_U48Ob5t5Yt 10:40:57 INFO - Expected file size: 16483174 10:40:57 INFO - Obtained file size: 16483174 10:41:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 10:41:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:41:04 INFO - Fetch https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 10:41:05 INFO - Http code: 200 10:41:05 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 10:41:05 INFO - x-amz-cf-id: 8VVFOB5pgR9RK6f31FwVOB0guZHQeepl0C80je52j9FAiPY0o0pomA== 10:41:05 INFO - x-amz-version-id: TZA3gShZs9RSIZOMFYgk3EIQ5n.9cgIl 10:41:06 INFO - Expected file size: 14409028 10:41:06 INFO - Obtained file size: 14409028 10:41:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:06 INFO - trying https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:41:06 INFO - Downloading https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 10:41:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/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 10:41:09 INFO - Downloaded 111710393 bytes. 10:41:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:41:09 INFO - mkdir: /builds/slave/test/properties 10:41:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:41:09 INFO - Writing to file /builds/slave/test/properties/build_url 10:41:09 INFO - Contents: 10:41:09 INFO - build_url:https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:41:10 INFO - Running post-action listener: _resource_record_post_action 10:41:10 INFO - Running post-action listener: set_extra_try_arguments 10:41:10 INFO - [mozharness: 2016-10-17 17:41:10.039802Z] Finished download-and-extract step (success) 10:41:10 INFO - [mozharness: 2016-10-17 17:41:10.039921Z] Running populate-webroot step. 10:41:10 INFO - Running pre-action listener: _resource_record_pre_action 10:41:10 INFO - Running main action method: populate_webroot 10:41:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:41:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:41:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:41:10 INFO - '--tppagecycles', 10:41:10 INFO - '7'], 10:41:10 INFO - 'tests': ['tsvgm']}}, 10:41:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:41:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:41:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:41:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:41:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:41:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:41:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['damp', 'tps']}, 10:41:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'tests': ['damp', 'tps']}, 10:41:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['dromaeo_dom']}, 10:41:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:41:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['basic_compositor_video']}, 10:41:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:41:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['a11yr', 10:41:10 INFO - 'ts_paint', 10:41:10 INFO - 'tpaint', 10:41:10 INFO - 'sessionrestore', 10:41:10 INFO - 'sessionrestore_no_auto_restore', 10:41:10 INFO - 'tabpaint']}, 10:41:10 INFO - 'other-e10s': {'tests': ['a11yr', 10:41:10 INFO - 'ts_paint', 10:41:10 INFO - 'tpaint', 10:41:10 INFO - 'sessionrestore', 10:41:10 INFO - 'sessionrestore_no_auto_restore', 10:41:10 INFO - 'tabpaint']}, 10:41:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:41:10 INFO - 'ts_paint', 10:41:10 INFO - 'tpaint', 10:41:10 INFO - 'sessionrestore', 10:41:10 INFO - 'sessionrestore_no_auto_restore', 10:41:10 INFO - 'tabpaint']}, 10:41:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:41:10 INFO - 'ts_paint', 10:41:10 INFO - 'tpaint', 10:41:10 INFO - 'sessionrestore', 10:41:10 INFO - 'sessionrestore_no_auto_restore', 10:41:10 INFO - 'tabpaint']}, 10:41:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['a11yr', 10:41:10 INFO - 'ts_paint', 10:41:10 INFO - 'tpaint', 10:41:10 INFO - 'sessionrestore', 10:41:10 INFO - 'sessionrestore_no_auto_restore', 10:41:10 INFO - 'tabpaint']}, 10:41:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['a11yr', 10:41:10 INFO - 'ts_paint', 10:41:10 INFO - 'tpaint', 10:41:10 INFO - 'sessionrestore', 10:41:10 INFO - 'sessionrestore_no_auto_restore', 10:41:10 INFO - 'tabpaint']}, 10:41:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['tsvgx', 10:41:10 INFO - 'tsvgr_opacity', 10:41:10 INFO - 'tart', 10:41:10 INFO - 'tscrollx', 10:41:10 INFO - 'cart']}, 10:41:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:41:10 INFO - 'tsvgr_opacity', 10:41:10 INFO - 'tart', 10:41:10 INFO - 'tscrollx', 10:41:10 INFO - 'cart']}, 10:41:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['tp5o']}, 10:41:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'tests': ['tp5o']}, 10:41:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'talos_options': ['--disable-e10s', 10:41:10 INFO - '--xperf_path', 10:41:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:41:10 INFO - 'tests': ['tp5n']}, 10:41:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'talos_options': ['--xperf_path', 10:41:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:41:10 INFO - 'tests': ['tp5n']}}, 10:41:10 INFO - 'talos.zip': {'path': '', 10:41:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:41:10 INFO - Running post-action listener: _resource_record_post_action 10:41:10 INFO - [mozharness: 2016-10-17 17:41:10.058646Z] Finished populate-webroot step (success) 10:41:10 INFO - [mozharness: 2016-10-17 17:41:10.058755Z] Running create-virtualenv step. 10:41:10 INFO - Running pre-action listener: _resource_record_pre_action 10:41:10 INFO - Running main action method: create_virtualenv 10:41:10 INFO - Creating virtualenv /builds/slave/test/build/venv 10:41:10 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 10:41:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:41:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:41:10 INFO - Using real prefix '/tools/python27' 10:41:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:41:10 INFO - Installing distribute.............................................................................................................................................................................................done. 10:41:13 INFO - Installing pip.................done. 10:41:13 INFO - Return code: 0 10:41:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:41:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:41:14 INFO - Reading from file tmpfile_stdout 10:41:14 INFO - Output received: 10:41:14 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) 10:41:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:41: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')]} 10:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41: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')]} 10:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:14 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 0x109758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd841524ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10956cd30>, '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 0x109392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109564b20>, '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.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 10:41:14 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 10:41:14 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 10:41:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IAwKbIT4Hj/Render', 10:41:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:14 INFO - 'HOME': '/Users/cltbld', 10:41:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:14 INFO - 'LOGNAME': 'cltbld', 10:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:14 INFO - 'MOZ_NO_REMOTE': '1', 10:41:14 INFO - 'NO_EM_RESTART': '1', 10:41:14 INFO - 'PAGER': '/bin/cat', 10:41:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:14 INFO - 'PWD': '/builds/slave/test', 10:41:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:14 INFO - 'SHELL': '/bin/bash', 10:41:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ci2Rrjglo0/Listeners', 10:41:14 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 10:41:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:14 INFO - 'USER': 'cltbld', 10:41:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:14 INFO - 'XPC_FLAGS': '0x0', 10:41:14 INFO - 'XPC_SERVICE_NAME': '0', 10:41:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:14 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 10:41:14 INFO - Cleaning up... 10:41:14 INFO - Return code: 0 10:41:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:41: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')]} 10:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41: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')]} 10:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:14 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 0x109758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd841524ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10956cd30>, '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 0x109392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109564b20>, '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.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 10:41:14 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 10:41:14 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 10:41:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IAwKbIT4Hj/Render', 10:41:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:14 INFO - 'HOME': '/Users/cltbld', 10:41:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:14 INFO - 'LOGNAME': 'cltbld', 10:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:14 INFO - 'MOZ_NO_REMOTE': '1', 10:41:14 INFO - 'NO_EM_RESTART': '1', 10:41:14 INFO - 'PAGER': '/bin/cat', 10:41:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:14 INFO - 'PWD': '/builds/slave/test', 10:41:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:14 INFO - 'SHELL': '/bin/bash', 10:41:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ci2Rrjglo0/Listeners', 10:41:14 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 10:41:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:14 INFO - 'USER': 'cltbld', 10:41:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:14 INFO - 'XPC_FLAGS': '0x0', 10:41:14 INFO - 'XPC_SERVICE_NAME': '0', 10:41:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:14 INFO - Downloading/unpacking psutil>=3.1.1 10:41:14 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 10:41:14 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 10:41:14 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 10:41:14 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 10:41:14 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 10:41:14 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 10:41:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:41:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:41:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:41:17 INFO - Installing collected packages: psutil 10:41:17 INFO - Running setup.py install for psutil 10:41:17 INFO - building 'psutil._psutil_osx' extension 10:41:17 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 10:41:18 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 10:41:18 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 10:41:18 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 10:41:18 INFO - building 'psutil._psutil_posix' extension 10:41:18 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 10:41:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:41:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:41:18 INFO - ^ 10:41:18 INFO - 1 warning generated. 10:41:18 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 10:41:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:41:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:41:19 INFO - Successfully installed psutil 10:41:19 INFO - Cleaning up... 10:41:19 INFO - Return code: 0 10:41:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd841524ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10956cd30>, '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 0x109392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109564b20>, '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.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 10:41:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:41:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IAwKbIT4Hj/Render', 10:41:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:19 INFO - 'HOME': '/Users/cltbld', 10:41:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:19 INFO - 'LOGNAME': 'cltbld', 10:41:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:19 INFO - 'MOZ_NO_REMOTE': '1', 10:41:19 INFO - 'NO_EM_RESTART': '1', 10:41:19 INFO - 'PAGER': '/bin/cat', 10:41:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:19 INFO - 'PWD': '/builds/slave/test', 10:41:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:19 INFO - 'SHELL': '/bin/bash', 10:41:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ci2Rrjglo0/Listeners', 10:41:19 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 10:41:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:19 INFO - 'USER': 'cltbld', 10:41:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:19 INFO - 'XPC_FLAGS': '0x0', 10:41:19 INFO - 'XPC_SERVICE_NAME': '0', 10:41:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:41:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:41:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:41:21 INFO - Installing collected packages: mozsystemmonitor 10:41:21 INFO - Running setup.py install for mozsystemmonitor 10:41:22 INFO - Successfully installed mozsystemmonitor 10:41:22 INFO - Cleaning up... 10:41:22 INFO - Return code: 0 10:41:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:41:22 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')]} 10:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:22 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')]} 10:41:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:22 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 0x109758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd841524ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10956cd30>, '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 0x109392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109564b20>, '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.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 10:41:22 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 10:41:22 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 10:41:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IAwKbIT4Hj/Render', 10:41:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:22 INFO - 'HOME': '/Users/cltbld', 10:41:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:22 INFO - 'LOGNAME': 'cltbld', 10:41:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:22 INFO - 'MOZ_NO_REMOTE': '1', 10:41:22 INFO - 'NO_EM_RESTART': '1', 10:41:22 INFO - 'PAGER': '/bin/cat', 10:41:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:22 INFO - 'PWD': '/builds/slave/test', 10:41:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:22 INFO - 'SHELL': '/bin/bash', 10:41:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ci2Rrjglo0/Listeners', 10:41:22 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 10:41:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:22 INFO - 'USER': 'cltbld', 10:41:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:22 INFO - 'XPC_FLAGS': '0x0', 10:41:22 INFO - 'XPC_SERVICE_NAME': '0', 10:41:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:22 INFO - Downloading/unpacking jsonschema==2.5.1 10:41: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 10:41: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 10:41: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 10:41: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 10:41: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 10:41: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 10:41:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:41:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:41: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 10:41: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 10:41: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 10:41: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 10:41: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 10:41: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 10:41:25 INFO - Downloading functools32-3.2.3-2.tar.gz 10:41:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:41:25 INFO - warning: no files found matching '*.txt' 10:41:25 INFO - no previously-included directories found matching 'build' 10:41:25 INFO - no previously-included directories found matching 'dist' 10:41:25 INFO - no previously-included directories found matching '.git*' 10:41:25 INFO - Installing collected packages: jsonschema, functools32 10:41:25 INFO - Running setup.py install for functools32 10:41:25 INFO - warning: no files found matching '*.txt' 10:41:25 INFO - no previously-included directories found matching 'build' 10:41:25 INFO - no previously-included directories found matching 'dist' 10:41:25 INFO - no previously-included directories found matching '.git*' 10:41:25 INFO - Successfully installed jsonschema functools32 10:41:25 INFO - Cleaning up... 10:41:25 INFO - Return code: 0 10:41:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:41:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd841524ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10956cd30>, '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 0x109392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109564b20>, '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.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 10:41:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:41:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:41:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IAwKbIT4Hj/Render', 10:41:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:25 INFO - 'HOME': '/Users/cltbld', 10:41:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:25 INFO - 'LOGNAME': 'cltbld', 10:41:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:25 INFO - 'MOZ_NO_REMOTE': '1', 10:41:25 INFO - 'NO_EM_RESTART': '1', 10:41:25 INFO - 'PAGER': '/bin/cat', 10:41:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:25 INFO - 'PWD': '/builds/slave/test', 10:41:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:25 INFO - 'SHELL': '/bin/bash', 10:41:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ci2Rrjglo0/Listeners', 10:41:25 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 10:41:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:25 INFO - 'USER': 'cltbld', 10:41:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:25 INFO - 'XPC_FLAGS': '0x0', 10:41:25 INFO - 'XPC_SERVICE_NAME': '0', 10:41:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:41:25 INFO - Cleaning up... 10:41:25 INFO - Return code: 0 10:41:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:41:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd841524ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10956cd30>, '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 0x109392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109564b20>, '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.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 10:41:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:41:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:41:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IAwKbIT4Hj/Render', 10:41:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:25 INFO - 'HOME': '/Users/cltbld', 10:41:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:25 INFO - 'LOGNAME': 'cltbld', 10:41:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:25 INFO - 'MOZ_NO_REMOTE': '1', 10:41:25 INFO - 'NO_EM_RESTART': '1', 10:41:25 INFO - 'PAGER': '/bin/cat', 10:41:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:25 INFO - 'PWD': '/builds/slave/test', 10:41:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:25 INFO - 'SHELL': '/bin/bash', 10:41:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ci2Rrjglo0/Listeners', 10:41:25 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 10:41:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:25 INFO - 'USER': 'cltbld', 10:41:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:25 INFO - 'XPC_FLAGS': '0x0', 10:41:25 INFO - 'XPC_SERVICE_NAME': '0', 10:41:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:26 INFO - Downloading/unpacking blobuploader==1.2.4 10:41: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 10:41: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 10:41: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 10:41: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 10:41: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 10:41: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 10:41:28 INFO - Downloading blobuploader-1.2.4.tar.gz 10:41:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:41:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:41:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:41:29 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 10:41:29 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 10:41:29 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 10:41:29 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 10:41:29 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 10:41:29 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 10:41:29 INFO - Downloading docopt-0.6.1.tar.gz 10:41:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:41:29 INFO - Installing collected packages: blobuploader, requests, docopt 10:41:29 INFO - Running setup.py install for blobuploader 10:41:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:41:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:41:30 INFO - Running setup.py install for requests 10:41:30 INFO - Running setup.py install for docopt 10:41:30 INFO - Successfully installed blobuploader requests docopt 10:41:30 INFO - Cleaning up... 10:41:30 INFO - Return code: 0 10:41:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:30 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 0x109758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd841524ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10956cd30>, '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 0x109392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109564b20>, '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.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 10:41:30 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 10:41:30 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 10:41:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IAwKbIT4Hj/Render', 10:41:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:30 INFO - 'HOME': '/Users/cltbld', 10:41:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:30 INFO - 'LOGNAME': 'cltbld', 10:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:30 INFO - 'MOZ_NO_REMOTE': '1', 10:41:30 INFO - 'NO_EM_RESTART': '1', 10:41:30 INFO - 'PAGER': '/bin/cat', 10:41:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:30 INFO - 'PWD': '/builds/slave/test', 10:41:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:30 INFO - 'SHELL': '/bin/bash', 10:41:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ci2Rrjglo0/Listeners', 10:41:30 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 10:41:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:30 INFO - 'USER': 'cltbld', 10:41:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:30 INFO - 'XPC_FLAGS': '0x0', 10:41:30 INFO - 'XPC_SERVICE_NAME': '0', 10:41:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:41:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-GS5sqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:41:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-zFyKKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:41:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-sp5Nmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:41:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-QZ2VSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:41:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-4Kp3gh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:41:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-gncDGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:41:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-hfI6Xt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:41:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-z6RxLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:41:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-XC3eJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:41:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:41:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-AxW1HJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:41:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:41:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-gfmzbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:41:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:41:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-zHSAAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:41:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:41:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-Kp_V8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:41:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:41:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-DOLdx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:41:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-ZOhhFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:41:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-0AyT8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:41:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:41:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-6jxlJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:41:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:41:32 INFO - Running setup.py install for manifestparser 10:41:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:41:33 INFO - Running setup.py install for mozcrash 10:41:33 INFO - Running setup.py install for mozdebug 10:41:33 INFO - Running setup.py install for mozdevice 10:41:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:41:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:41:33 INFO - Running setup.py install for mozfile 10:41:33 INFO - Running setup.py install for mozhttpd 10:41:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:41:33 INFO - Running setup.py install for mozinfo 10:41:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:41:33 INFO - Running setup.py install for mozInstall 10:41:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:41:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:41:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:41:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:41:34 INFO - Running setup.py install for mozleak 10:41:34 INFO - Running setup.py install for mozlog 10:41:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:41:34 INFO - Running setup.py install for moznetwork 10:41:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:41:34 INFO - Running setup.py install for mozprocess 10:41:34 INFO - Running setup.py install for mozprofile 10:41:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:41:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:41:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:41:34 INFO - Running setup.py install for mozrunner 10:41:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:41:35 INFO - Running setup.py install for mozscreenshot 10:41:35 INFO - Running setup.py install for moztest 10:41:35 INFO - Running setup.py install for mozversion 10:41:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:41:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:41:35 INFO - Cleaning up... 10:41:35 INFO - Return code: 0 10:41:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:41:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:35 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 0x109758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd841524ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10956cd30>, '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 0x109392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109564b20>, '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.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 10:41:35 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 10:41:35 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 10:41:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IAwKbIT4Hj/Render', 10:41:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:35 INFO - 'HOME': '/Users/cltbld', 10:41:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:35 INFO - 'LOGNAME': 'cltbld', 10:41:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:35 INFO - 'MOZ_NO_REMOTE': '1', 10:41:35 INFO - 'NO_EM_RESTART': '1', 10:41:35 INFO - 'PAGER': '/bin/cat', 10:41:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:35 INFO - 'PWD': '/builds/slave/test', 10:41:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:35 INFO - 'SHELL': '/bin/bash', 10:41:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ci2Rrjglo0/Listeners', 10:41:35 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 10:41:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:35 INFO - 'USER': 'cltbld', 10:41:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:35 INFO - 'XPC_FLAGS': '0x0', 10:41:35 INFO - 'XPC_SERVICE_NAME': '0', 10:41:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:41:35 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-_g7X9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:41:35 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)) 10:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:41:35 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-x0uovg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:41:35 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)) 10:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:41:35 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-x83Erq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:41:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-CE8z26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:41:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-1W8rAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:41:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-Qa78qE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:41:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-3ElPh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:41:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-9MsvUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:41:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-x0eyNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:41:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-yprOLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:41:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-BxDMSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:41:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-MFhwLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:41:37 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)) 10:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:41:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-lGbnY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:41:37 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)) 10:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:41:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-cnH2za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:41:37 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)) 10:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-pxu_SU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:37 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)) 10:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:41:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-Oqivxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:41:37 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)) 10:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:41:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-akn7vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:41:37 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)) 10:41:37 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)) 10:41:37 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)) 10:41:37 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)) 10:41:37 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)) 10:41:37 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)) 10:41:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:41:37 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 10:41:37 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 10:41:37 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 10:41:37 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 10:41:37 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 10:41:37 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 10:41:40 INFO - Downloading blessings-1.6.tar.gz 10:41:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:41:40 INFO - Installing collected packages: blessings 10:41:40 INFO - Running setup.py install for blessings 10:41:40 INFO - Successfully installed blessings 10:41:40 INFO - Cleaning up... 10:41:40 INFO - Return code: 0 10:41:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:41:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:41:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:41:40 INFO - Reading from file tmpfile_stdout 10:41:40 INFO - Current package versions: 10:41:40 INFO - blessings == 1.6 10:41:40 INFO - blobuploader == 1.2.4 10:41:40 INFO - docopt == 0.6.1 10:41:40 INFO - functools32 == 3.2.3-2 10:41:40 INFO - jsonschema == 2.5.1 10:41:40 INFO - manifestparser == 1.1 10:41:40 INFO - mozInstall == 1.12 10:41:40 INFO - mozcrash == 0.17 10:41:40 INFO - mozdebug == 0.1 10:41:40 INFO - mozdevice == 0.48 10:41:40 INFO - mozfile == 1.2 10:41:40 INFO - mozhttpd == 0.7 10:41:40 INFO - mozinfo == 0.9 10:41:40 INFO - mozleak == 0.1 10:41:40 INFO - mozlog == 3.4 10:41:40 INFO - moznetwork == 0.27 10:41:40 INFO - mozprocess == 0.23 10:41:40 INFO - mozprofile == 0.28 10:41:40 INFO - mozrunner == 6.12 10:41:40 INFO - mozscreenshot == 0.1 10:41:40 INFO - mozsystemmonitor == 0.3 10:41:40 INFO - moztest == 0.7 10:41:40 INFO - mozversion == 1.4 10:41:40 INFO - psutil == 3.1.1 10:41:40 INFO - requests == 1.2.3 10:41:40 INFO - wsgiref == 0.1.2 10:41:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:41:40 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')]} 10:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:41 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 0x109758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd841524ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10956cd30>, '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 0x109392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109564b20>, '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.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 10:41:41 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 10:41:41 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 10:41:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IAwKbIT4Hj/Render', 10:41:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:41 INFO - 'HOME': '/Users/cltbld', 10:41:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:41 INFO - 'LOGNAME': 'cltbld', 10:41:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:41 INFO - 'MOZ_NO_REMOTE': '1', 10:41:41 INFO - 'NO_EM_RESTART': '1', 10:41:41 INFO - 'PAGER': '/bin/cat', 10:41:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:41 INFO - 'PWD': '/builds/slave/test', 10:41:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:41 INFO - 'SHELL': '/bin/bash', 10:41:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ci2Rrjglo0/Listeners', 10:41:41 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 10:41:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:41 INFO - 'USER': 'cltbld', 10:41:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:41 INFO - 'XPC_FLAGS': '0x0', 10:41:41 INFO - 'XPC_SERVICE_NAME': '0', 10:41:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:41 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)) 10:41:41 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)) 10:41:41 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)) 10:41:41 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)) 10:41:41 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)) 10:41:41 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)) 10:41:41 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)) 10:41:41 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)) 10:41:41 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)) 10:41:41 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)) 10:41:41 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)) 10:41:41 INFO - Cleaning up... 10:41:41 INFO - Return code: 0 10:41:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd841524ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10956cd30>, '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 0x109392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109564b20>, '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.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 10:41:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:41:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IAwKbIT4Hj/Render', 10:41:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:42 INFO - 'HOME': '/Users/cltbld', 10:41:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:42 INFO - 'LOGNAME': 'cltbld', 10:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:42 INFO - 'MOZ_NO_REMOTE': '1', 10:41:42 INFO - 'NO_EM_RESTART': '1', 10:41:42 INFO - 'PAGER': '/bin/cat', 10:41:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:42 INFO - 'PWD': '/builds/slave/test', 10:41:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:42 INFO - 'SHELL': '/bin/bash', 10:41:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ci2Rrjglo0/Listeners', 10:41:42 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 10:41:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:42 INFO - 'USER': 'cltbld', 10:41:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:42 INFO - 'XPC_FLAGS': '0x0', 10:41:42 INFO - 'XPC_SERVICE_NAME': '0', 10:41:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:41:42 INFO - Cleaning up... 10:41:42 INFO - Return code: 0 10:41:42 INFO - Running post-action listener: _resource_record_post_action 10:41:42 INFO - Running post-action listener: _start_resource_monitoring 10:41:42 INFO - Starting resource monitoring. 10:41:42 INFO - [mozharness: 2016-10-17 17:41:42.369666Z] Finished create-virtualenv step (success) 10:41:42 INFO - [mozharness: 2016-10-17 17:41:42.370678Z] Running install step. 10:41:42 INFO - Running pre-action listener: _resource_record_pre_action 10:41:42 INFO - Running main action method: install 10:41:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:41:42 INFO - Reading from file tmpfile_stdout 10:41:42 INFO - Detecting whether we're running mozinstall >=1.0... 10:41:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:41:42 INFO - Reading from file tmpfile_stdout 10:41:42 INFO - Output received: 10:41:42 INFO - Usage: mozinstall [options] installer 10:41:42 INFO - Options: 10:41:42 INFO - -h, --help show this help message and exit 10:41:42 INFO - -d DEST, --destination=DEST 10:41:42 INFO - Directory to install application into. [default: 10:41:42 INFO - "/builds/slave/test"] 10:41:42 INFO - --app=APP Application being installed. [default: firefox] 10:41:42 INFO - mkdir: /builds/slave/test/build/application 10:41:42 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'] 10:41:42 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 10:42:16 INFO - Reading from file tmpfile_stdout 10:42:16 INFO - Output received: 10:42:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:42:16 INFO - Running post-action listener: _resource_record_post_action 10:42:16 INFO - [mozharness: 2016-10-17 17:42:16.803731Z] Finished install step (success) 10:42:16 INFO - [mozharness: 2016-10-17 17:42:16.803868Z] Running run-tests step. 10:42:16 INFO - Running pre-action listener: _resource_record_pre_action 10:42:16 INFO - Running main action method: run_tests 10:42:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:42:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:42:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:42:16 INFO - 2016-10-17 10:42:16.818 screenresolution[1636:7399] starting screenresolution argv=screenresolution get 10:42:16 INFO - 2016-10-17 10:42:16.821 screenresolution[1636:7399] Display 0: 1600x1200x32@60 10:42:16 INFO - 2016-10-17 10:42:16.826 screenresolution[1637:7401] starting screenresolution argv=screenresolution list 10:42:16 INFO - Available Modes on Display 0 10:42:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:42:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:42:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:42:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:42:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:42:17 INFO - Intel Iris: 10:42:17 INFO - Chipset Model: Intel Iris 10:42:17 INFO - Type: GPU 10:42:17 INFO - Bus: Built-In 10:42:17 INFO - VRAM (Dynamic, Max): 1536 MB 10:42:17 INFO - Vendor: Intel (0x8086) 10:42:17 INFO - Device ID: 0x0a2e 10:42:17 INFO - Revision ID: 0x0009 10:42:17 INFO - Displays: 10:42:17 INFO - Display: 10:42:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:42:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:42:17 INFO - Display Serial Number: 1600x1200 60 10:42:17 INFO - Main Display: Yes 10:42:17 INFO - Mirror: Off 10:42:17 INFO - Online: Yes 10:42:17 INFO - Rotation: Supported 10:42:17 INFO - Return code: 0 10:42:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:42:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:42:17 INFO - Python 2.7.3 10:42:17 INFO - Return code: 0 10:42:17 INFO - grabbing minidump binary from tooltool 10:42: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')]} 10:42:17 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 0x10956cd30>, '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 0x109392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109564b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:42:17 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 10:42:17 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 10:42:17 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 10:42:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:42:17 INFO - Return code: 0 10:42:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:42:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:42:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:42:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:42:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:42:17 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/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0092', '--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 10:42:17 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/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0092 --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 10:42:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IAwKbIT4Hj/Render', 10:42:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:17 INFO - 'HOME': '/Users/cltbld', 10:42:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:17 INFO - 'LOGNAME': 'cltbld', 10:42:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:42:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:42:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:17 INFO - 'MOZ_NO_REMOTE': '1', 10:42:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:42:17 INFO - 'NO_EM_RESTART': '1', 10:42:17 INFO - 'PAGER': '/bin/cat', 10:42:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:17 INFO - 'PWD': '/builds/slave/test', 10:42:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:42:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:17 INFO - 'SHELL': '/bin/bash', 10:42:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ci2Rrjglo0/Listeners', 10:42:17 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 10:42:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:17 INFO - 'USER': 'cltbld', 10:42:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:17 INFO - 'XPC_FLAGS': '0x0', 10:42:17 INFO - 'XPC_SERVICE_NAME': '0', 10:42:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:17 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/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0092', '--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 10:42:17 INFO - mozversion application_buildid: 20161017082949 10:42:17 INFO - mozversion application_changeset: 6fc7fc30b5c5f093b60095b0da5317d38771c5d4 10:42:17 INFO - mozversion application_display_name: Nightly 10:42:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:42:17 INFO - mozversion application_name: Firefox 10:42:17 INFO - mozversion application_remotingname: firefox 10:42:17 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:42:17 INFO - mozversion application_vendor: Mozilla 10:42:17 INFO - mozversion application_version: 52.0a1 10:42:17 INFO - mozversion platform_buildid: 20161017082949 10:42:17 INFO - mozversion platform_changeset: 6fc7fc30b5c5f093b60095b0da5317d38771c5d4 10:42:17 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:42:17 INFO - mozversion platform_version: 52.0a1 10:42:17 INFO - using testdate: 1476726137 10:42:17 INFO - actual date: 1476726137 10:42:17 INFO - starting webserver on 'localhost:49273' 10:42:17 INFO - SUITE-START | Running 2 tests 10:42:17 INFO - TEST-START | dromaeo_css 10:42:17 INFO - operating with platform_type : mac_ 10:42:17 INFO - Initialising browser for dromaeo_css test... 10:42:17 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpSLWRPO/profile http://localhost:49273/getInfo.html) 10:42:19 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 10:42:19 INFO - PROCESS | 1647 | colorDepth:24 10:42:19 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 10:42:19 INFO - PROCESS | 1647 | __metrics 10:42:19 INFO - PROCESS | 1647 | 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" 10:42:19 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:42:20 INFO - TEST-INFO | 1647: exit 0 10:42:20 INFO - Browser initialized. 10:42:20 INFO - Running cycle 1/1 for dromaeo_css test... 10:42:20 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpSLWRPO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:42:32 INFO - PROCESS | 1649 | RSS: Main: 240291840 10:42:32 INFO - PROCESS | 1649 | 10:43:24 INFO - PROCESS | 1649 | 1476726204020 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] 10:45:37 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 10:45:39 INFO - PROCESS | 1649 | RSS: Main: 409636864 10:45:39 INFO - PROCESS | 1649 | 10:48:42 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 10:48:43 INFO - PROCESS | 1649 | RSS: Main: 368771072 10:48:43 INFO - PROCESS | 1649 | 10:51:45 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 10:51:46 INFO - PROCESS | 1649 | RSS: Main: 330268672 10:51:46 INFO - PROCESS | 1649 | 10:54:48 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 10:54:49 INFO - PROCESS | 1649 | RSS: Main: 345501696 10:54:49 INFO - PROCESS | 1649 | 10:57:52 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 10:57:53 INFO - PROCESS | 1649 | RSS: Main: 325324800 10:57:53 INFO - PROCESS | 1649 | 11:00:56 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 11:00:56 INFO - PROCESS | 1649 | RSS: Main: 438386688 11:00:56 INFO - PROCESS | 1649 | 11:00:56 INFO - PROCESS | 1649 | __start_tp_report 11:00:56 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 11:00:56 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 11:00:56 INFO - PROCESS | 1649 | |i|pagename|runs| 11:00:56 INFO - PROCESS | 1649 | |0;dojo.html;2665.5335316319643;3320;3338.9666103338973;3345.5483509726178;3381.7970921744695;5571.303587051619;5616.634918730285;5656.91514627281;5700.828975130742;5741.9951508486;5619.5785316101355;5643.040683083876;5683.971580142103;5685.800996965107;5861.794837180689;5155.5360017598405;5166.689998600096;5219.45195754446;5224.1119009768245;5237.947620523803;5130.2817605535065;5237.10969135246;5269.3676758789;5271.551918086967;5287.920681189786;5232.607554433433;5246.973765131169;5266.131088370415;5294.5764338853;5320.521153096221;5131.820386286462;5161.741912904377;5168.508991645787;5205.9219111713355;5227.451117632631;5134.358205224347;5139.820106296262;5170.715610641413;5199.948000519984;5216;5577.776888924479;5633.901389229102;5662.8301150965535;5764.279465066867;5765.1640512125505;6173.2900716417735;6197.721102550351;6198.4731297839735;6216.96891515544;6218.564700470969;5679.432056794329;6096.512279017668;6110.948077334365;6114.388561143895;6140.228185505611;5665.745041473144;5678.886422271531;5690.146478028246;5690.5447564194765;5713.114467257582;6208.751649933952;6291.307956124822;6305.117283580303;6306.400891915261;6313.43179113882;5935.169076329317;5948.315943666447;6011.008183649737;6016.398360163948;6051.848703782441;6213.875722485525;6250.6874656267;6258.780942666985;6263.530235232376;6268.373162683786;6092.786752463731;6110.205673262536;6168.568200226031;6182.814515564541;6200.906986395207;5994.130851026577;6053.364396738367;6145.8156255312415;6220.34686357935;6250.031245157008;4841.394825646794;4893.339399181136;4896.46138924718;4906.950930490721;4925.40895092584;4377.890552736143;4381.452318460192;4408.867733967986;4410.184115938561;4435.689501734848;5674.914876276859;5678.3186017678145;5687.090065589486;5687.829365119053;5745.597808153389;5903.26209223847;6076.0278355462915;6080.331163572004;6152.64641778808;6208.751649934043;4924.9507504924495;5668.121441176624;5744.885102297931;5759.85600359986;5794.82615521535;5377.085895397791;5388;5398.541124004425;5410.83767486976;5440.211169380417;6231.719572619334;6244.625322480666;6254.3745625437095;6297.275813281532;6305.905411418924;6142.959235178615;6190.969045154746;6201.6899155041165;6271.655058971618;6293.842653933688;6277.340879207617;6296.968515157578;6311.368863113652;6328.46208072319;6340.239171299474;5948.5538584605465;6055.333913269625;6080.48315893154;6085.847853803513;6128.172696686069;6081.27024757032;6112.929053818819;6140.412224875342;6167.506599471942;6195.597286176564;6020.518358531394;6024.728887199999;6040.577159598786;6060.181875446935;6065.484433822998;5949.464548190684;6022.00636894925;6030.185924900083;6058.151858739868;6059.72731227087;5750.166225897305;5798.275215598051;5832.94167058324;5853.09277062056;5918.615290006136;6044.607100538451;6073.423024812636;6084.148219249397;6111.205543279345;6113.663748493697;5714.657120572779;5765.019946609171;5781.653100813965;5860.238169037998;5894.882102358015 11:00:56 INFO - PROCESS | 1649 | |1;ext.html;442.1907908527395;484.22040514771237;500.29458463566357;540.6999115490903;551.8427248234257;40044.39933401001;41045.79477102614;41143.177136457256;41356.99999999996;43493.78253108738;41984.95037624061;42232.366514502304;42447;43030.56969430305;43113.568864311346;9513.857292140607;9678.9837067108;9692.321537492378;9722;9732.951335243313;9514.38156519824;9636.61453541861;9636.855447168298;9642.469664168468;9674.564644590975;9622.470764107971;9666.951665241664;9668.758281042996;9671;9688.46713430761;9592.664256751015;9636.807263854684;9637.759056023655;9668;9673.467959262238;8483.406161568693;9529.57116929733;9571.090746379152;9619.086186812241;9633.421994680342;39779.20441591152;40145;40146;40190.397144042865;40203.59796402028;3253.6420993690676;3254.2840575073446;3284.1297056280105;3298.835058247102;3363.1592101974506;29344.679489423026;30067.248318791986;30464;30554.84722576395;31471.685283147333;3151.8108913465267;3176.6188057433023;3215.9196020099685;3304.454764963778;3313.8177400242976;493997.5300123512;498957;500348.49825751;500592.4970375125;512509;60623;60917;61061.38938610646;61081.694591527645;61225;34250.82874585661;34570.82714586461;34933.1266718329;36475.63524364722;64983.02525462122;14324.283785810876;16513;16628.58428539272;16657.416990405276;16694.415695450843;10995.890041099647;12795.488180472677;12826.871731282568;12846.421911014028;12848.550300739429;15600.92199538995;18836.52908677294;18848.81151188498;18856.434306970812;18931.90534047321;8024.077231118379;8112.716054938049;8179.877301840477;8183.6906095024515;8218.383621228431;778.4667502760627;805.6545568899951;1010.1968934696934;1105.2815669814686;1134.1663877050362;25778.87110564473;29569;29687.406251874843;30360.69639303623;30418;1030.8093002794358;1077.8167711489066;1090.574675876409;1106.4246591772232;1118.2228351295935;14933.551993440215;15496.457623983106;15555.377784888704;15561;15829.52511424636;645202;650156;651181;652510;653079;56913.43086569164;57604.13593796013;57635.423645763;57668;57680.13479797806;1191.4876603060768;1257.8930790882878;1319.5117806411497;1395.6022533578084;1436.8491308412883;29871;30870.69129308768;30997.535036974914;31668.841655791573;31919.6808031926;47929.76035119942;48558.27162592635;48724;48726;48763.26855097106;515453;517146;517568;520249;520565;21252;25792.486314895195;25865.741342586334;26584.335391615365;26768.59847102334;46970.765146175414;47076.7646161767;47395.28907066466;48171.5182848167;48354.791130222024;38291.04272393212;38571.80714096524;38921;40005.59994400019;40255.798721006206;38652.613473866026;38796;38842.8347149586;39958.2008359837;40452.79773601113;37617.62382376141;38045.239095218494;38847.028824279616;38872.805635971636;38889.805550972065;32900.34199316048;33472.995810125736;34416.48375274423;34452;34600;24881.50236995286;25000.62499062479;25250.368740780894;25568.48863022692;25702.871485642452 11:00:56 INFO - PROCESS | 1649 | |2;jquery.html;375.3562640072277;376.5312186328021;384.54431498674063;395.0439935356437;407.9632833045025;15623.687526249483;19182.712259316122;21083.262085827;21399.395045371602;21799.782002179974;20540.38378848633;20906.954652267403;21145.57708845822;21403.571928561418;21464.678029829527;5555.250041244431;5768.5385169186575;5843.8539036524;6077.058056001327;6153.90769138461;3924.2347742190286;4136.77247751375;6118.938810611881;6184.84537886552;6194.225721784777;4161.500619925613;5787.710614469281;5994.760209591611;6088.604240724361;6132.969335153341;5845.85385365365;6042.154098426224;6096.847578810521;6124.785632502885;6125;5906.763729450817;5908.231929849135;5920.111983202511;5925.229720136354;5928.140419639171;13326.533571325053;17676.823231767645;18581.62836743271;24678.383040423952;24838.379040523952;2190.58378908007;2273.874936878471;2284.1091974130104;2453.889574969113;2694.4611077784325;8477.83044339116;9930.652427165014;11596.072314214842;15078.849211507853;15717.292721827545;1786.1337251433044;1831.3142811693783;1842.8065053169357;1858.7862395824518;1912.0917564157053;105609.47195263974;119505.40247298707;121119.39440302741;121433.99999999911;121816.39091804484;11263.155263355282;11366.943165284287;11515.25150865191;11532.59635912739;11747.236429632047;14536.982411231113;14593.489227876973;14600.12399256048;14605.78091328631;14611.78082328766;8334.49993000422;8670.436421632574;8677.1756683115;8825.161609647203;8839;7094.822629434202;8360.623771930277;8434.57827108642;8493.320534357237;8651.870221946676;3877.3408520551575;4507.278835386388;4565.497795242521;4568.291914753218;4665.953340466552;1652.239969613967;1736.9305227790999;1837.4303965770655;1923.7306777051224;1925.9807401925802;744.454204419163;792.6445777720048;976.8437050072097;1009.8737657792776;1030.258214085857;20347.69478457824;21960.670589941165;21961.341159765234;22025.33923982283;22061;919.2921450483224;926.7822061815468;933.332667142996;937.4188003437912;944.9574769135405;10544.525496352682;11330.433478326051;11332.716682083013;11352.432378381049;11379.374134422686;524699;535525;536905;558687;564284;70469.94295085578;73431.2656873435;86054.56972715222;127468;131288.68711312764;930.8976012638605;943.1700103908516;951.3103000324709;1012.250934308621;1028.018242578336;18855.7743746656;19440.70838937389;19736.506587335432;20039.29862454807;20276.493087672927;42734.78632606817;47528.762356187995;51705;53139.46860531345;53443;71891;72197;72271.91592126228;72284;72804.63597681977;6739.528233023641;7342.155652100077;7394.705926462955;7490.438217133846;7667.271609197117;10400.687979360631;11807;11900.166988310735;12079.879201207876;13027.47890084386;7158.521458695113;8982.86525702102;10600.25798194119;10926.781464370828;10974.561017559208;10255.94872025635;10575.312604680672;10620.362778233331;10893.945530272298;11199.10407167441;10870.673879783619;11295.265807722471;11366.090712743126;11496.195266331277;11551.942240288745;5763.481286684051;7268.600226987566;8014.719484818003;8077.83844323122;8241.423100382915;9306.255499560155;9745.658901938397;10443.321184123008;10726.034656880918;11258.718532036764 11:00:56 INFO - PROCESS | 1649 | |3;mootools.html;1537.3850459816072;1584.4850423612327;1780.5815633326165;1818.70900655895;1921.529225339792;8528.189821966911;9063.90936090639;9082.455052696843;9089.227415669677;9123.543822808866;7663.808404789883;8059.234372734581;8299.709510167146;8858.33562482813;8936;2368.1119580157483;2389.7012873390827;2398.4843258699375;2398.6162214045758;2400.0279886645935;2357.693499845018;2377.595808712523;2395.8682272475007;2401.2916189724074;2401.327628264089;2364.9054037838464;2387.8925448354867;2389.187676190095;2390.9760902391013;2391.8325717199805;2326.197461875667;2335;2346.0498498108295;2346.4133966508457;2364.0189321431585;2320.2691152286975;2345.3550273674878;2345.976540234593;2347.495288512978;2350.7071110889115;8859.424137431111;9059.954700226523;9062.864057039149;9080.273578113734;9118.133777291147;5277.20841873722;5462.535684466825;5465.207544906006;5466.753996070186;5468.808591699271;3228.322052369005;3610.115521697201;3611.602723700391;3689.3543629864666;3690.7785532868115;906.5240748606968;1083.5232497700986;1091.47063674118;1091.8962698543626;1107.213878146517;10339.862615112332;11547.53809847605;11562.248453850389;11666.300021998622;11673.591424300193;4601.976990115028;4676.625869930397;4841.540053694894;4854.101991131652;4868.172410690191;10057.69826905194;10083.59665613382;10097.5456104474;10102.19182465401;10109.747256318651;5459.235707001023;5459.645123066988;5464.9180262296095;5468.808591699271;5475.397706252309;4382.101669157815;4559;4580.564846339593;4936.333594964705;4989.977054703777;3878.3212937735784;3966.960330396717;3999.8800035998966;4058.573849745734;4090.2433049885867;1282.8332316798942;1287.665207046156;1291.819145319656;1305.3147097773744;1307.8234438350892;914.4376208631777;923.9168474837297;934.2245935873209;939.8167357365248;947.4173383369179;5801.7389217485315;5895.263092113486;6015.699215039231;6177.505799536027;6237.407446292595;927.4991504587447;962.9359557688794;996.3025881882711;1016.8474728790741;1078.1698092468912;3521.9471707924404;3726.105734623725;3914.941275880864;4090.3229675832767;4132.235536425711;10212.948935255276;10889.965453281926;10922.781544369069;10924.2899211551;10934.890651093547;10885.782284354427;10893;10900.073493752961;10906.018458338787;10928.453577321103;7816.6091695415;8203.794905127419;8236.094029656853;8237.341012718967;8344.207300306462;5357.491038351351;5954.791582294599;5994.07091900756;6177.660228687465;6230.813075607739;9851.704448866545;10098.293119481566;10130.746731331776;10146.239032072772;10159.187265018632;9893.950530247303;10183.490825458846;10249.9487502562;10376.16990640762;10424.635137770141;7097.574145551283;7353.558786472829;7522;7540.283673051052;7733.845323093394;7432.442566807403;8694.347923905605;8704.434211776213;8707.69523066715;8709.608067637098;7510.061242344707;7645.541267523967;7688.884666729891;7809.062912450542;7825;7367.77896663101;7551.622418879144;7554.546727196386;7571;7635.923640763522;7005.0893383859775;7156.105486814148;7163.713451461883;7169.390601798906;7170.390516805921;4587.784237177084;4968.3044373788425;5040.672356296829;5123.308353372396;5139.306193663957;5047.596192304681;5108.131617624938;5215.869603259949;5241.423443421144;5337.359516858002 11:00:56 INFO - PROCESS | 1649 | |4;prototype.html;279.45924635829664;299.82759913049995;302.8546297777067;309.8961847780993;318.6383454778828;4318.301061836352;4507.481639611446;4931.679440836352;5097.668651537648;5270.156774916014;5154.458781827911;5197.922031169535;5225.242339860727;5238.5023422774775;5253.553447956919;3578.964210357889;4028.9323329317754;4037.555868854431;4042.292598795199;4063.8984025399304;3572.69561133069;3922.4900762900925;4037.062210139135;4039.555648878621;4046.2803308593875;3543.291341731664;3909.6481316681493;3965.9688480995005;4030.9798451007705;4094.5905409458965;4035.737677050997;4047.311956967293;4051.534073581546;4058.5738497457783;4061.390791381287;3083.72246497814;3456.1705190754287;3546.3261980223674;3571.8749843755604;3583.569971603424;4703.977289765399;4757.952420475785;4765.451973023078;4772.069446457942;5237.869053273661;4545.659075569312;4546.204414227497;4585.211767410679;4593.83921562747;4627.329037289574;4744.881377965544;5501.202325662756;5508.411046807993;5551.806361632228;5557.026725891007;3720.1815600567834;3887.803391084057;3893.824777885002;3905.1213476967737;3952.4071389291835;5723.395854886058;5734.788518429693;5744.626599271034;5749;5758.337791154029;3579.9439165446256;3725.850965961331;3935.901602459904;3940.4680368150507;3943.9014024649614;3396.117009577528;3837.059920319484;3940.4877365942243;3982.362821948532;4017.4777278954134;3922.9607703923166;4069;4432.024954510002;4551.544845515422;4650.18621741194;5500.762328476061;5510.752016159283;5515.255440515479;5538.144721518256;5561.833145005657;1091;1188.989359044805;1313.082898967499;1326.5025615394227;1326.8938484921184;839.8152406470567;870.9346798990101;929.8279818233649;937.5218638494417;943.1228957069991;4475.753833539186;5029.974850125726;5206.505381988705;5303.920441193385;5415.83752487426;4978.427480839678;5442.755076021588;5691.8292451226525;5741.483266506035;5745.626534275259;5136.332276803992;5141.845744627667;5153.273653326103;5182.196759502283;5378.7041712705395;2450.6181469571875;2800.159952014428;2913.7960342776228;2946.690597487233;2957.807742496731;4648.465426475931;4926.482719314492;5759.942400576025;5922.318933322636;5967.701614919323;5261.921306132233;5517.006938750983;5904.291485021739;5923.437273459014;5926.259217597801;5267.655893127521;5480.424555421703;5956.434138756811;5967.850803729768;6001.399860013964;4924.8276310328965;5002;5442;5734.30837902828;5825.058345535477;5651.2935883014625;5654.688992105309;5687.687177205128;5688.317401911631;5727.971360143172;5777.306723193244;5934.732937017759;5942.881142377215;5958;6031.487323577545;3563.429851223792;3804.4483549885667;3834.7183425861567;3843.962130224768;3898;5192.922106168486;5361.410244872983;5361.785528578813;5758.654480731169;5787.56593255516;4686.812527498998;4766.904661906812;4803.687760295569;5602.915956260741;5609.49514543693;4879.4876537963;5278.340207474066;5526.861828454321;5556.91664625039;5584.441555844383;4685.695429797052;4839.370881785346;5150.6137039721425;5164.406093299319;5364.624476286623;4927.260910863399;5521.585881058856;5558.305211848519;5561.360443549043;5586.720663966704;4572.954270457386;4578.816847326069;4830.478399304208;5356.9911283268775;5391.703456309784 11:00:56 INFO - PROCESS | 1649 | |5;yui.html;750.5196674128555;756.2097607999641;795.6021989005501;826.1366871619165;877.6621000914646;14812.555623331304;15031.924840375783;15518.534443966684;15576.143312117843;15879.682406351894;13992.160470371784;14331.42674293027;14339.283035848192;14342.784858227134;14355.066920650177;712.5154894671621;881.4579033894157;905.9762468410687;907.9682211122612;911.1344222988155;739.8853177757456;750.9474336796427;884.7390019944132;911.4622372800046;920.1304766995192;968.2254196642693;972.1445128287097;972.222222222223;974.9317547771659;974.9610015599368;962.7400601837535;965.1072757699135;965.5751469353497;966.2511553545988;967.5936106835146;561.5816216918381;578.7887421091314;630.2877748144567;710.8045287545915;716.6309350684402;15104.471343503035;15159.696806063926;15165.696686066327;15178.241087945558;15191.392344306214;13375.464981400732;13678;13724;13779.311034448338;14089.718205635932;12349.75300493985;12424.036462497961;12507.3746312684;12567.371631418484;13033.087683862133;4401.735895846259;4456.442944631921;4544.272916333404;4569;4661.114388266218;104593.95406045919;104866.47566762194;105530;105984.4700776499;106088;8870.689525866565;10029;10412.68761937143;11021.283616564897;11327.60353387644;19626.21495140207;19790;20182.88994105338;20305.28931487391;20483.975801210177;7528.134264559646;7621.237876212445;7670.386369090459;7868.2131786822;7929.167437419103;4176.686748493875;4207.8316867325575;4310.741355518679;4336.804843782037;4462.665300102505;3447.3622379859808;3570.1610121621397;3574.999000279926;3579.1231148368297;3805.276997370491;717.3722992381666;718.7664009197032;719.093941633535;734.7097896331027;739.7928579997609;602.7619090459244;623.5759683415231;636.3064259956669;682.9726810927507;706.0926709178783;9517.524123793783;9569.569369378394;9634.566444510014;9745;9777.364471309342;602.3795490644643;605.5700452678648;613.0865011133379;630.2027934662656;642.9646369449632;4046.7571945683353;4643.257078867365;4672.742999135012;4678.672492925531;4723.433188017391;113095;162715;163691.1815440939;165652;166307;111625;138882;140743.3331000215;141581;144090;97971.53042704507;117532;118013;120570.39714801546;120650.39674801745;9274.860877086714;9462.479563624067;9753.951230243803;9806.607735690492;9832.60669573209;53496.930061397776;56841.43158568361;58647.4135258642;59411.1088333684;59911;81028.37943240984;86510.56744716236;87996.5600171995;90405;92306;13001;13088.149270297401;13130.671733206364;13310.600681979557;13323;14206.644833879236;14322.355494002586;14442.927785361426;14611.780823287872;14719.484818031746;11419.657410277705;13266.204027758366;13513.864861351654;13619.114757540729;14141.787873182117;13191.472341106248;13370.331483425594;13653.931730341683;13698.246596437291;14315.069520481135;12900.41948112356;13240.470381184645;13305.800412994007;13407.731845362843;13519.986001050158;10164;10773.353598784099;11268.83096753533;11272.098232141245;11526.366049867001;10967.91201794641;11387.772244554897;11532.25040372373;11602.825957610812;11626.70933226676 11:00:56 INFO - PROCESS | 1649 | __end_tp_report 11:00:56 INFO - PROCESS | 1649 | __start_cc_report 11:00:56 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,2233 11:00:56 INFO - PROCESS | 1649 | __end_cc_report 11:00:56 INFO - PROCESS | 1649 | __startTimestamp1476727256859__endTimestamp 11:00:56 INFO - PROCESS | 1649 | 11:00:56 INFO - PROCESS | 1649 | ------- Summary: start ------- 11:00:56 INFO - PROCESS | 1649 | Number of tests: 6 11:00:56 INFO - PROCESS | 1649 | 11:00:56 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5693.30 Median:5860.24 stddev:639.92 (10.9%) stddev-sans-first:596.17 11:00:56 INFO - PROCESS | 1649 | Values: 2665.5 3320.0 3339.0 3345.5 3381.8 5571.3 5616.6 5656.9 5700.8 5742.0 5619.6 5643.0 5684.0 5685.8 5861.8 5155.5 5166.7 5219.5 5224.1 5237.9 5130.3 5237.1 5269.4 5271.6 5287.9 5232.6 5247.0 5266.1 5294.6 5320.5 5131.8 5161.7 5168.5 5205.9 5227.5 5134.4 5139.8 5170.7 5199.9 5216.0 5577.8 5633.9 5662.8 5764.3 5765.2 6173.3 6197.7 6198.5 6217.0 6218.6 5679.4 6096.5 6110.9 6114.4 6140.2 5665.7 5678.9 5690.1 5690.5 5713.1 6208.8 6291.3 6305.1 6306.4 6313.4 5935.2 5948.3 6011.0 6016.4 6051.8 6213.9 6250.7 6258.8 6263.5 6268.4 6092.8 6110.2 6168.6 6182.8 6200.9 5994.1 6053.4 6145.8 6220.3 6250.0 4841.4 4893.3 4896.5 4907.0 4925.4 4377.9 4381.5 4408.9 4410.2 4435.7 5674.9 5678.3 5687.1 5687.8 5745.6 5903.3 6076.0 6080.3 6152.6 6208.8 4925.0 5668.1 5744.9 5759.9 5794.8 5377.1 5388.0 5398.5 5410.8 5440.2 6231.7 6244.6 6254.4 6297.3 6305.9 6143.0 6191.0 6201.7 6271.7 6293.8 6277.3 6297.0 6311.4 6328.5 6340.2 5948.6 6055.3 6080.5 6085.8 6128.2 6081.3 6112.9 6140.4 6167.5 6195.6 6020.5 6024.7 6040.6 6060.2 6065.5 5949.5 6022.0 6030.2 6058.2 6059.7 5750.2 5798.3 5832.9 5853.1 5918.6 6044.6 6073.4 6084.1 6111.2 6113.7 5714.7 5765.0 5781.7 5860.2 5894.9 11:00:56 INFO - PROCESS | 1649 | 11:00:56 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:68626.89 Median:40025.00 stddev:149791.47 (374.2%) stddev-sans-first:150124.19 11:00:56 INFO - PROCESS | 1649 | Values: 442.2 484.2 500.3 540.7 551.8 40044.4 41045.8 41143.2 41357.0 43493.8 41985.0 42232.4 42447.0 43030.6 43113.6 9513.9 9679.0 9692.3 9722.0 9733.0 9514.4 9636.6 9636.9 9642.5 9674.6 9622.5 9667.0 9668.8 9671.0 9688.5 9592.7 9636.8 9637.8 9668.0 9673.5 8483.4 9529.6 9571.1 9619.1 9633.4 39779.2 40145.0 40146.0 40190.4 40203.6 3253.6 3254.3 3284.1 3298.8 3363.2 29344.7 30067.2 30464.0 30554.8 31471.7 3151.8 3176.6 3215.9 3304.5 3313.8 493997.5 498957.0 500348.5 500592.5 512509.0 60623.0 60917.0 61061.4 61081.7 61225.0 34250.8 34570.8 34933.1 36475.6 64983.0 14324.3 16513.0 16628.6 16657.4 16694.4 10995.9 12795.5 12826.9 12846.4 12848.6 15600.9 18836.5 18848.8 18856.4 18931.9 8024.1 8112.7 8179.9 8183.7 8218.4 778.5 805.7 1010.2 1105.3 1134.2 25778.9 29569.0 29687.4 30360.7 30418.0 1030.8 1077.8 1090.6 1106.4 1118.2 14933.6 15496.5 15555.4 15561.0 15829.5 645202.0 650156.0 651181.0 652510.0 653079.0 56913.4 57604.1 57635.4 57668.0 57680.1 1191.5 1257.9 1319.5 1395.6 1436.8 29871.0 30870.7 30997.5 31668.8 31919.7 47929.8 48558.3 48724.0 48726.0 48763.3 515453.0 517146.0 517568.0 520249.0 520565.0 21252.0 25792.5 25865.7 26584.3 26768.6 46970.8 47076.8 47395.3 48171.5 48354.8 38291.0 38571.8 38921.0 40005.6 40255.8 38652.6 38796.0 38842.8 39958.2 40452.8 37617.6 38045.2 38847.0 38872.8 38889.8 32900.3 33473.0 34416.5 34452.0 34600.0 24881.5 25000.6 25250.4 25568.5 25702.9 11:00:56 INFO - PROCESS | 1649 | 11:00:56 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:32255.37 Median:375.94 stddev:90684.92 (24121.9%) stddev-sans-first:90907.72 11:00:56 INFO - PROCESS | 1649 | Values: 375.4 376.5 384.5 395.0 408.0 15623.7 19182.7 21083.3 21399.4 21799.8 20540.4 20907.0 21145.6 21403.6 21464.7 5555.3 5768.5 5843.9 6077.1 6153.9 3924.2 4136.8 6118.9 6184.8 6194.2 4161.5 5787.7 5994.8 6088.6 6133.0 5845.9 6042.2 6096.8 6124.8 6125.0 5906.8 5908.2 5920.1 5925.2 5928.1 13326.5 17676.8 18581.6 24678.4 24838.4 2190.6 2273.9 2284.1 2453.9 2694.5 8477.8 9930.7 11596.1 15078.8 15717.3 1786.1 1831.3 1842.8 1858.8 1912.1 105609.5 119505.4 121119.4 121434.0 121816.4 11263.2 11366.9 11515.3 11532.6 11747.2 14537.0 14593.5 14600.1 14605.8 14611.8 8334.5 8670.4 8677.2 8825.2 8839.0 7094.8 8360.6 8434.6 8493.3 8651.9 3877.3 4507.3 4565.5 4568.3 4666.0 1652.2 1736.9 1837.4 1923.7 1926.0 744.5 792.6 976.8 1009.9 1030.3 20347.7 21960.7 21961.3 22025.3 22061.0 919.3 926.8 933.3 937.4 945.0 10544.5 11330.4 11332.7 11352.4 11379.4 524699.0 535525.0 536905.0 558687.0 564284.0 70469.9 73431.3 86054.6 127468.0 131288.7 930.9 943.2 951.3 1012.3 1028.0 18855.8 19440.7 19736.5 20039.3 20276.5 42734.8 47528.8 51705.0 53139.5 53443.0 71891.0 72197.0 72271.9 72284.0 72804.6 6739.5 7342.2 7394.7 7490.4 7667.3 10400.7 11807.0 11900.2 12079.9 13027.5 7158.5 8982.9 10600.3 10926.8 10974.6 10255.9 10575.3 10620.4 10893.9 11199.1 10870.7 11295.3 11366.1 11496.2 11551.9 5763.5 7268.6 8014.7 8077.8 8241.4 9306.3 9745.7 10443.3 10726.0 11258.7 11:00:56 INFO - PROCESS | 1649 | 11:00:56 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5778.32 Median:4847.82 stddev:3218.50 (66.4%) stddev-sans-first:3211.75 11:00:56 INFO - PROCESS | 1649 | Values: 1537.4 1584.5 1780.6 1818.7 1921.5 8528.2 9063.9 9082.5 9089.2 9123.5 7663.8 8059.2 8299.7 8858.3 8936.0 2368.1 2389.7 2398.5 2398.6 2400.0 2357.7 2377.6 2395.9 2401.3 2401.3 2364.9 2387.9 2389.2 2391.0 2391.8 2326.2 2335.0 2346.0 2346.4 2364.0 2320.3 2345.4 2346.0 2347.5 2350.7 8859.4 9060.0 9062.9 9080.3 9118.1 5277.2 5462.5 5465.2 5466.8 5468.8 3228.3 3610.1 3611.6 3689.4 3690.8 906.5 1083.5 1091.5 1091.9 1107.2 10339.9 11547.5 11562.2 11666.3 11673.6 4602.0 4676.6 4841.5 4854.1 4868.2 10057.7 10083.6 10097.5 10102.2 10109.7 5459.2 5459.6 5464.9 5468.8 5475.4 4382.1 4559.0 4580.6 4936.3 4990.0 3878.3 3967.0 3999.9 4058.6 4090.2 1282.8 1287.7 1291.8 1305.3 1307.8 914.4 923.9 934.2 939.8 947.4 5801.7 5895.3 6015.7 6177.5 6237.4 927.5 962.9 996.3 1016.8 1078.2 3521.9 3726.1 3914.9 4090.3 4132.2 10212.9 10890.0 10922.8 10924.3 10934.9 10885.8 10893.0 10900.1 10906.0 10928.5 7816.6 8203.8 8236.1 8237.3 8344.2 5357.5 5954.8 5994.1 6177.7 6230.8 9851.7 10098.3 10130.7 10146.2 10159.2 9894.0 10183.5 10249.9 10376.2 10424.6 7097.6 7353.6 7522.0 7540.3 7733.8 7432.4 8694.3 8704.4 8707.7 8709.6 7510.1 7645.5 7688.9 7809.1 7825.0 7367.8 7551.6 7554.5 7571.0 7635.9 7005.1 7156.1 7163.7 7169.4 7170.4 4587.8 4968.3 5040.7 5123.3 5139.3 5047.6 5108.1 5215.9 5241.4 5337.4 11:00:56 INFO - PROCESS | 1649 | 11:00:56 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4482.25 Median:4929.47 stddev:1373.95 (27.9%) stddev-sans-first:1341.11 11:00:56 INFO - PROCESS | 1649 | Values: 279.5 299.8 302.9 309.9 318.6 4318.3 4507.5 4931.7 5097.7 5270.2 5154.5 5197.9 5225.2 5238.5 5253.6 3579.0 4028.9 4037.6 4042.3 4063.9 3572.7 3922.5 4037.1 4039.6 4046.3 3543.3 3909.6 3966.0 4031.0 4094.6 4035.7 4047.3 4051.5 4058.6 4061.4 3083.7 3456.2 3546.3 3571.9 3583.6 4704.0 4758.0 4765.5 4772.1 5237.9 4545.7 4546.2 4585.2 4593.8 4627.3 4744.9 5501.2 5508.4 5551.8 5557.0 3720.2 3887.8 3893.8 3905.1 3952.4 5723.4 5734.8 5744.6 5749.0 5758.3 3579.9 3725.9 3935.9 3940.5 3943.9 3396.1 3837.1 3940.5 3982.4 4017.5 3923.0 4069.0 4432.0 4551.5 4650.2 5500.8 5510.8 5515.3 5538.1 5561.8 1091.0 1189.0 1313.1 1326.5 1326.9 839.8 870.9 929.8 937.5 943.1 4475.8 5030.0 5206.5 5303.9 5415.8 4978.4 5442.8 5691.8 5741.5 5745.6 5136.3 5141.8 5153.3 5182.2 5378.7 2450.6 2800.2 2913.8 2946.7 2957.8 4648.5 4926.5 5759.9 5922.3 5967.7 5261.9 5517.0 5904.3 5923.4 5926.3 5267.7 5480.4 5956.4 5967.9 6001.4 4924.8 5002.0 5442.0 5734.3 5825.1 5651.3 5654.7 5687.7 5688.3 5728.0 5777.3 5934.7 5942.9 5958.0 6031.5 3563.4 3804.4 3834.7 3844.0 3898.0 5192.9 5361.4 5361.8 5758.7 5787.6 4686.8 4766.9 4803.7 5602.9 5609.5 4879.5 5278.3 5526.9 5556.9 5584.4 4685.7 4839.4 5150.6 5164.4 5364.6 4927.3 5521.6 5558.3 5561.4 5586.7 4573.0 4578.8 4830.5 5357.0 5391.7 11:00:56 INFO - PROCESS | 1649 | 11:00:56 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:24946.62 Median:3577.06 stddev:40510.12 (1132.5%) stddev-sans-first:40583.02 11:00:56 INFO - PROCESS | 1649 | Values: 750.5 756.2 795.6 826.1 877.7 14812.6 15031.9 15518.5 15576.1 15879.7 13992.2 14331.4 14339.3 14342.8 14355.1 712.5 881.5 906.0 908.0 911.1 739.9 750.9 884.7 911.5 920.1 968.2 972.1 972.2 974.9 975.0 962.7 965.1 965.6 966.3 967.6 561.6 578.8 630.3 710.8 716.6 15104.5 15159.7 15165.7 15178.2 15191.4 13375.5 13678.0 13724.0 13779.3 14089.7 12349.8 12424.0 12507.4 12567.4 13033.1 4401.7 4456.4 4544.3 4569.0 4661.1 104594.0 104866.5 105530.0 105984.5 106088.0 8870.7 10029.0 10412.7 11021.3 11327.6 19626.2 19790.0 20182.9 20305.3 20484.0 7528.1 7621.2 7670.4 7868.2 7929.2 4176.7 4207.8 4310.7 4336.8 4462.7 3447.4 3570.2 3575.0 3579.1 3805.3 717.4 718.8 719.1 734.7 739.8 602.8 623.6 636.3 683.0 706.1 9517.5 9569.6 9634.6 9745.0 9777.4 602.4 605.6 613.1 630.2 643.0 4046.8 4643.3 4672.7 4678.7 4723.4 113095.0 162715.0 163691.2 165652.0 166307.0 111625.0 138882.0 140743.3 141581.0 144090.0 97971.5 117532.0 118013.0 120570.4 120650.4 9274.9 9462.5 9754.0 9806.6 9832.6 53496.9 56841.4 58647.4 59411.1 59911.0 81028.4 86510.6 87996.6 90405.0 92306.0 13001.0 13088.1 13130.7 13310.6 13323.0 14206.6 14322.4 14442.9 14611.8 14719.5 11419.7 13266.2 13513.9 13619.1 14141.8 13191.5 13370.3 13653.9 13698.2 14315.1 12900.4 13240.5 13305.8 13407.7 13520.0 10164.0 10773.4 11268.8 11272.1 11526.4 10967.9 11387.8 11532.3 11602.8 11626.7 11:00:56 INFO - PROCESS | 1649 | -------- Summary: end -------- 11:00:56 INFO - PROCESS | 1649 | 11:00:57 INFO - TEST-INFO | 1649: exit 0 11:00:57 INFO - TEST-OK | dromaeo_css | took 1120139ms 11:00:57 INFO - TEST-START | kraken 11:00:57 INFO - operating with platform_type : mac_ 11:00:57 INFO - Initialising browser for kraken test... 11:00:57 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpaC1SIw/profile http://localhost:49273/getInfo.html) 11:00:59 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 11:00:59 INFO - PROCESS | 1659 | colorDepth:24 11:00:59 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 11:00:59 INFO - PROCESS | 1659 | __metrics 11:01:00 INFO - PROCESS | 1659 | 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" 11:01:00 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:01:00 INFO - TEST-INFO | 1659: exit 0 11:01:00 INFO - Browser initialized. 11:01:00 INFO - Running cycle 1/1 for kraken test... 11:01:00 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpaC1SIw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:01:12 INFO - PROCESS | 1660 | RSS: Main: 238809088 11:01:12 INFO - PROCESS | 1660 | 11:01:15 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 11:01:16 INFO - PROCESS | 1660 | RSS: Main: 290537472 11:01:16 INFO - PROCESS | 1660 | 11:01:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 11:01:19 INFO - PROCESS | 1660 | RSS: Main: 289013760 11:01:19 INFO - PROCESS | 1660 | 11:01:23 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 11:01:23 INFO - PROCESS | 1660 | RSS: Main: 382431232 11:01:23 INFO - PROCESS | 1660 | 11:01:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 11:01:26 INFO - PROCESS | 1660 | RSS: Main: 283738112 11:01:26 INFO - PROCESS | 1660 | 11:01:28 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:01:29 INFO - PROCESS | 1660 | RSS: Main: 287899648 11:01:29 INFO - PROCESS | 1660 | 11:01:31 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 11:01:32 INFO - PROCESS | 1660 | RSS: Main: 326590464 11:01:32 INFO - PROCESS | 1660 | 11:01:35 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 11:01:35 INFO - PROCESS | 1660 | RSS: Main: 327098368 11:01:35 INFO - PROCESS | 1660 | 11:01:38 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 11:01:39 INFO - PROCESS | 1660 | RSS: Main: 325124096 11:01:39 INFO - PROCESS | 1660 | 11:01:40 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:01:41 INFO - PROCESS | 1660 | RSS: Main: 302297088 11:01:41 INFO - PROCESS | 1660 | 11:01:43 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:01:43 INFO - PROCESS | 1660 | RSS: Main: 305295360 11:01:43 INFO - PROCESS | 1660 | 11:01:46 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:01:46 INFO - PROCESS | 1660 | RSS: Main: 311357440 11:01:46 INFO - PROCESS | 1660 | 11:01:49 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:01:50 INFO - PROCESS | 1660 | RSS: Main: 317927424 11:01:50 INFO - PROCESS | 1660 | 11:01:53 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:01:54 INFO - PROCESS | 1660 | RSS: Main: 317779968 11:01:54 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 11:01:55 INFO - PROCESS | 1660 | RSS: Main: 361975808 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | __start_tp_report 11:01:55 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 11:01:55 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 11:01:55 INFO - PROCESS | 1660 | |i|pagename|runs| 11:01:55 INFO - PROCESS | 1660 | |0;ai-astar;112;114;114;113;114;114;113;113;113;108 11:01:55 INFO - PROCESS | 1660 | |1;audio-beat-detection;149;110;107;150;112;112;112;113;141;111 11:01:55 INFO - PROCESS | 1660 | |2;audio-dft;185;186;183;184;212;254;187;184;181;246 11:01:55 INFO - PROCESS | 1660 | |3;audio-fft;79;60;59;87;64;57;59;58;83;58 11:01:55 INFO - PROCESS | 1660 | |4;audio-oscillator;85;72;90;69;67;73;93;68;69;89 11:01:55 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;87;85;87;84;85;84;88;85;87;85 11:01:55 INFO - PROCESS | 1660 | |6;imaging-darkroom;93;93;93;92;94;94;93;93;93;92 11:01:55 INFO - PROCESS | 1660 | |7;imaging-desaturate;81;81;81;80;84;83;84;86;81;83 11:01:55 INFO - PROCESS | 1660 | |8;json-parse-financial;56;52;54;53;53;53;54;53;54;54 11:01:55 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;68;76;66;69;75;70;68;69;69;70 11:01:55 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;90;87;86;89;88;84;88;84;87;94 11:01:55 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;149;144;171;149;143;143;145;146;183;145 11:01:55 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;169;170;170;176;171;169;169;174;170;170 11:01:55 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;68;68;69;69;67;68;68;68;69;68 11:01:55 INFO - PROCESS | 1660 | __end_tp_report 11:01:55 INFO - PROCESS | 1660 | __start_cc_report 11:01:55 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1983 11:01:55 INFO - PROCESS | 1660 | __end_cc_report 11:01:55 INFO - PROCESS | 1660 | __startTimestamp1476727315980__endTimestamp 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | ------- Summary: start ------- 11:01:55 INFO - PROCESS | 1660 | Number of tests: 14 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.50 stddev:1.81 (1.6%) stddev-sans-first:1.90 11:01:55 INFO - PROCESS | 1660 | Values: 112.0 114.0 114.0 113.0 114.0 114.0 113.0 113.0 113.0 108.0 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:121.70 Median:112.50 stddev:17.46 (15.5%) stddev-sans-first:15.48 11:01:55 INFO - PROCESS | 1660 | Values: 149.0 110.0 107.0 150.0 112.0 112.0 112.0 113.0 141.0 111.0 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:200.20 Median:186.50 stddev:27.74 (14.9%) stddev-sans-first:28.88 11:01:55 INFO - PROCESS | 1660 | Values: 185.0 186.0 183.0 184.0 212.0 254.0 187.0 184.0 181.0 246.0 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:66.40 Median:62.00 stddev:11.76 (19.0%) stddev-sans-first:11.55 11:01:55 INFO - PROCESS | 1660 | Values: 79.0 60.0 59.0 87.0 64.0 57.0 59.0 58.0 83.0 58.0 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:79.00 stddev:10.44 (13.2%) stddev-sans-first:10.71 11:01:55 INFO - PROCESS | 1660 | Values: 85.0 72.0 90.0 69.0 67.0 73.0 93.0 68.0 69.0 89.0 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.00 stddev:1.42 (1.6%) stddev-sans-first:1.42 11:01:55 INFO - PROCESS | 1660 | Values: 87.0 85.0 87.0 84.0 85.0 84.0 88.0 85.0 87.0 85.0 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 11:01:55 INFO - PROCESS | 1660 | Values: 93.0 93.0 93.0 92.0 94.0 94.0 93.0 93.0 93.0 92.0 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:82.40 Median:83.00 stddev:1.90 (2.3%) stddev-sans-first:1.94 11:01:55 INFO - PROCESS | 1660 | Values: 81.0 81.0 81.0 80.0 84.0 83.0 84.0 86.0 81.0 83.0 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:1.07 (2.0%) stddev-sans-first:0.71 11:01:55 INFO - PROCESS | 1660 | Values: 56.0 52.0 54.0 53.0 53.0 53.0 54.0 53.0 54.0 54.0 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:69.50 stddev:3.13 (4.5%) stddev-sans-first:3.23 11:01:55 INFO - PROCESS | 1660 | Values: 68.0 76.0 66.0 69.0 75.0 70.0 68.0 69.0 69.0 70.0 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:88.00 stddev:2.95 (3.3%) stddev-sans-first:3.00 11:01:55 INFO - PROCESS | 1660 | Values: 90.0 87.0 86.0 89.0 88.0 84.0 88.0 84.0 87.0 94.0 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:151.80 Median:147.50 stddev:13.74 (9.3%) stddev-sans-first:14.54 11:01:55 INFO - PROCESS | 1660 | Values: 149.0 144.0 171.0 149.0 143.0 143.0 145.0 146.0 183.0 145.0 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.80 Median:170.00 stddev:2.35 (1.4%) stddev-sans-first:2.40 11:01:55 INFO - PROCESS | 1660 | Values: 169.0 170.0 170.0 176.0 171.0 169.0 169.0 174.0 170.0 170.0 11:01:55 INFO - PROCESS | 1660 | 11:01:55 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:0.63 (0.9%) stddev-sans-first:0.67 11:01:55 INFO - PROCESS | 1660 | Values: 68.0 68.0 69.0 69.0 67.0 68.0 68.0 68.0 69.0 68.0 11:01:55 INFO - PROCESS | 1660 | -------- Summary: end -------- 11:01:55 INFO - PROCESS | 1660 | 11:01:56 INFO - TEST-INFO | 1660: exit 0 11:01:56 INFO - TEST-OK | kraken | took 59319ms 11:01:56 INFO - SUITE-END | took 1179s 11:01:57 INFO - Completed test suite (00:19:39) 11:01:57 INFO - javascript benchmark 11:01:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2665.5335316319643, 3320.0, 3338.9666103338973, 3345.5483509726178, 3381.7970921744695, 5571.303587051619, 5616.634918730285, 5656.91514627281, 5700.828975130742, 5741.9951508486, 5619.5785316101355, 5643.040683083876, 5683.971580142103, 5685.800996965107, 5861.794837180689, 5155.5360017598405, 5166.689998600096, 5219.45195754446, 5224.1119009768245, 5237.947620523803, 5130.2817605535065, 5237.10969135246, 5269.3676758789, 5271.551918086967, 5287.920681189786, 5232.607554433433, 5246.973765131169, 5266.131088370415, 5294.5764338853, 5320.521153096221, 5131.820386286462, 5161.741912904377, 5168.508991645787, 5205.9219111713355, 5227.451117632631, 5134.358205224347, 5139.820106296262, 5170.715610641413, 5199.948000519984, 5216.0, 5577.776888924479, 5633.901389229102, 5662.8301150965535, 5764.279465066867, 5765.1640512125505, 6173.2900716417735, 6197.721102550351, 6198.4731297839735, 6216.96891515544, 6218.564700470969, 5679.432056794329, 6096.512279017668, 6110.948077334365, 6114.388561143895, 6140.228185505611, 5665.745041473144, 5678.886422271531, 5690.146478028246, 5690.5447564194765, 5713.114467257582, 6208.751649933952, 6291.307956124822, 6305.117283580303, 6306.400891915261, 6313.43179113882, 5935.169076329317, 5948.315943666447, 6011.008183649737, 6016.398360163948, 6051.848703782441, 6213.875722485525, 6250.6874656267, 6258.780942666985, 6263.530235232376, 6268.373162683786, 6092.786752463731, 6110.205673262536, 6168.568200226031, 6182.814515564541, 6200.906986395207, 5994.130851026577, 6053.364396738367, 6145.8156255312415, 6220.34686357935, 6250.031245157008, 4841.394825646794, 4893.339399181136, 4896.46138924718, 4906.950930490721, 4925.40895092584, 4377.890552736143, 4381.452318460192, 4408.867733967986, 4410.184115938561, 4435.689501734848, 5674.914876276859, 5678.3186017678145, 5687.090065589486, 5687.829365119053, 5745.597808153389, 5903.26209223847, 6076.0278355462915, 6080.331163572004, 6152.64641778808, 6208.751649934043, 4924.9507504924495, 5668.121441176624, 5744.885102297931, 5759.85600359986, 5794.82615521535, 5377.085895397791, 5388.0, 5398.541124004425, 5410.83767486976, 5440.211169380417, 6231.719572619334, 6244.625322480666, 6254.3745625437095, 6297.275813281532, 6305.905411418924, 6142.959235178615, 6190.969045154746, 6201.6899155041165, 6271.655058971618, 6293.842653933688, 6277.340879207617, 6296.968515157578, 6311.368863113652, 6328.46208072319, 6340.239171299474, 5948.5538584605465, 6055.333913269625, 6080.48315893154, 6085.847853803513, 6128.172696686069, 6081.27024757032, 6112.929053818819, 6140.412224875342, 6167.506599471942, 6195.597286176564, 6020.518358531394, 6024.728887199999, 6040.577159598786, 6060.181875446935, 6065.484433822998, 5949.464548190684, 6022.00636894925, 6030.185924900083, 6058.151858739868, 6059.72731227087, 5750.166225897305, 5798.275215598051, 5832.94167058324, 5853.09277062056, 5918.615290006136, 6044.607100538451, 6073.423024812636, 6084.148219249397, 6111.205543279345, 6113.663748493697, 5714.657120572779, 5765.019946609171, 5781.653100813965, 5860.238169037998, 5894.882102358015], "value": 5650.59590463841, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [442.1907908527395, 484.22040514771237, 500.29458463566357, 540.6999115490903, 551.8427248234257, 40044.39933401001, 41045.79477102614, 41143.177136457256, 41356.99999999996, 43493.78253108738, 41984.95037624061, 42232.366514502304, 42447.0, 43030.56969430305, 43113.568864311346, 9513.857292140607, 9678.9837067108, 9692.321537492378, 9722.0, 9732.951335243313, 9514.38156519824, 9636.61453541861, 9636.855447168298, 9642.469664168468, 9674.564644590975, 9622.470764107971, 9666.951665241664, 9668.758281042996, 9671.0, 9688.46713430761, 9592.664256751015, 9636.807263854684, 9637.759056023655, 9668.0, 9673.467959262238, 8483.406161568693, 9529.57116929733, 9571.090746379152, 9619.086186812241, 9633.421994680342, 39779.20441591152, 40145.0, 40146.0, 40190.397144042865, 40203.59796402028, 3253.6420993690676, 3254.2840575073446, 3284.1297056280105, 3298.835058247102, 3363.1592101974506, 29344.679489423026, 30067.248318791986, 30464.0, 30554.84722576395, 31471.685283147333, 3151.8108913465267, 3176.6188057433023, 3215.9196020099685, 3304.454764963778, 3313.8177400242976, 493997.5300123512, 498957.0, 500348.49825751, 500592.4970375125, 512509.0, 60623.0, 60917.0, 61061.38938610646, 61081.694591527645, 61225.0, 34250.82874585661, 34570.82714586461, 34933.1266718329, 36475.63524364722, 64983.02525462122, 14324.283785810876, 16513.0, 16628.58428539272, 16657.416990405276, 16694.415695450843, 10995.890041099647, 12795.488180472677, 12826.871731282568, 12846.421911014028, 12848.550300739429, 15600.92199538995, 18836.52908677294, 18848.81151188498, 18856.434306970812, 18931.90534047321, 8024.077231118379, 8112.716054938049, 8179.877301840477, 8183.6906095024515, 8218.383621228431, 778.4667502760627, 805.6545568899951, 1010.1968934696934, 1105.2815669814686, 1134.1663877050362, 25778.87110564473, 29569.0, 29687.406251874843, 30360.69639303623, 30418.0, 1030.8093002794358, 1077.8167711489066, 1090.574675876409, 1106.4246591772232, 1118.2228351295935, 14933.551993440215, 15496.457623983106, 15555.377784888704, 15561.0, 15829.52511424636, 645202.0, 650156.0, 651181.0, 652510.0, 653079.0, 56913.43086569164, 57604.13593796013, 57635.423645763, 57668.0, 57680.13479797806, 1191.4876603060768, 1257.8930790882878, 1319.5117806411497, 1395.6022533578084, 1436.8491308412883, 29871.0, 30870.69129308768, 30997.535036974914, 31668.841655791573, 31919.6808031926, 47929.76035119942, 48558.27162592635, 48724.0, 48726.0, 48763.26855097106, 515453.0, 517146.0, 517568.0, 520249.0, 520565.0, 21252.0, 25792.486314895195, 25865.741342586334, 26584.335391615365, 26768.59847102334, 46970.765146175414, 47076.7646161767, 47395.28907066466, 48171.5182848167, 48354.791130222024, 38291.04272393212, 38571.80714096524, 38921.0, 40005.59994400019, 40255.798721006206, 38652.613473866026, 38796.0, 38842.8347149586, 39958.2008359837, 40452.79773601113, 37617.62382376141, 38045.239095218494, 38847.028824279616, 38872.805635971636, 38889.805550972065, 32900.34199316048, 33472.995810125736, 34416.48375274423, 34452.0, 34600.0, 24881.50236995286, 25000.62499062479, 25250.368740780894, 25568.48863022692, 25702.871485642452], "value": 19692.593000607867, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.3562640072277, 376.5312186328021, 384.54431498674063, 395.0439935356437, 407.9632833045025, 15623.687526249483, 19182.712259316122, 21083.262085827, 21399.395045371602, 21799.782002179974, 20540.38378848633, 20906.954652267403, 21145.57708845822, 21403.571928561418, 21464.678029829527, 5555.250041244431, 5768.5385169186575, 5843.8539036524, 6077.058056001327, 6153.90769138461, 3924.2347742190286, 4136.77247751375, 6118.938810611881, 6184.84537886552, 6194.225721784777, 4161.500619925613, 5787.710614469281, 5994.760209591611, 6088.604240724361, 6132.969335153341, 5845.85385365365, 6042.154098426224, 6096.847578810521, 6124.785632502885, 6125.0, 5906.763729450817, 5908.231929849135, 5920.111983202511, 5925.229720136354, 5928.140419639171, 13326.533571325053, 17676.823231767645, 18581.62836743271, 24678.383040423952, 24838.379040523952, 2190.58378908007, 2273.874936878471, 2284.1091974130104, 2453.889574969113, 2694.4611077784325, 8477.83044339116, 9930.652427165014, 11596.072314214842, 15078.849211507853, 15717.292721827545, 1786.1337251433044, 1831.3142811693783, 1842.8065053169357, 1858.7862395824518, 1912.0917564157053, 105609.47195263974, 119505.40247298707, 121119.39440302741, 121433.99999999911, 121816.39091804484, 11263.155263355282, 11366.943165284287, 11515.25150865191, 11532.59635912739, 11747.236429632047, 14536.982411231113, 14593.489227876973, 14600.12399256048, 14605.78091328631, 14611.78082328766, 8334.49993000422, 8670.436421632574, 8677.1756683115, 8825.161609647203, 8839.0, 7094.822629434202, 8360.623771930277, 8434.57827108642, 8493.320534357237, 8651.870221946676, 3877.3408520551575, 4507.278835386388, 4565.497795242521, 4568.291914753218, 4665.953340466552, 1652.239969613967, 1736.9305227790999, 1837.4303965770655, 1923.7306777051224, 1925.9807401925802, 744.454204419163, 792.6445777720048, 976.8437050072097, 1009.8737657792776, 1030.258214085857, 20347.69478457824, 21960.670589941165, 21961.341159765234, 22025.33923982283, 22061.0, 919.2921450483224, 926.7822061815468, 933.332667142996, 937.4188003437912, 944.9574769135405, 10544.525496352682, 11330.433478326051, 11332.716682083013, 11352.432378381049, 11379.374134422686, 524699.0, 535525.0, 536905.0, 558687.0, 564284.0, 70469.94295085578, 73431.2656873435, 86054.56972715222, 127468.0, 131288.68711312764, 930.8976012638605, 943.1700103908516, 951.3103000324709, 1012.250934308621, 1028.018242578336, 18855.7743746656, 19440.70838937389, 19736.506587335432, 20039.29862454807, 20276.493087672927, 42734.78632606817, 47528.762356187995, 51705.0, 53139.46860531345, 53443.0, 71891.0, 72197.0, 72271.91592126228, 72284.0, 72804.63597681977, 6739.528233023641, 7342.155652100077, 7394.705926462955, 7490.438217133846, 7667.271609197117, 10400.687979360631, 11807.0, 11900.166988310735, 12079.879201207876, 13027.47890084386, 7158.521458695113, 8982.86525702102, 10600.25798194119, 10926.781464370828, 10974.561017559208, 10255.94872025635, 10575.312604680672, 10620.362778233331, 10893.945530272298, 11199.10407167441, 10870.673879783619, 11295.265807722471, 11366.090712743126, 11496.195266331277, 11551.942240288745, 5763.481286684051, 7268.600226987566, 8014.719484818003, 8077.83844323122, 8241.423100382915, 9306.255499560155, 9745.658901938397, 10443.321184123008, 10726.034656880918, 11258.718532036764], "value": 9298.64791992926, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1537.3850459816072, 1584.4850423612327, 1780.5815633326165, 1818.70900655895, 1921.529225339792, 8528.189821966911, 9063.90936090639, 9082.455052696843, 9089.227415669677, 9123.543822808866, 7663.808404789883, 8059.234372734581, 8299.709510167146, 8858.33562482813, 8936.0, 2368.1119580157483, 2389.7012873390827, 2398.4843258699375, 2398.6162214045758, 2400.0279886645935, 2357.693499845018, 2377.595808712523, 2395.8682272475007, 2401.2916189724074, 2401.327628264089, 2364.9054037838464, 2387.8925448354867, 2389.187676190095, 2390.9760902391013, 2391.8325717199805, 2326.197461875667, 2335.0, 2346.0498498108295, 2346.4133966508457, 2364.0189321431585, 2320.2691152286975, 2345.3550273674878, 2345.976540234593, 2347.495288512978, 2350.7071110889115, 8859.424137431111, 9059.954700226523, 9062.864057039149, 9080.273578113734, 9118.133777291147, 5277.20841873722, 5462.535684466825, 5465.207544906006, 5466.753996070186, 5468.808591699271, 3228.322052369005, 3610.115521697201, 3611.602723700391, 3689.3543629864666, 3690.7785532868115, 906.5240748606968, 1083.5232497700986, 1091.47063674118, 1091.8962698543626, 1107.213878146517, 10339.862615112332, 11547.53809847605, 11562.248453850389, 11666.300021998622, 11673.591424300193, 4601.976990115028, 4676.625869930397, 4841.540053694894, 4854.101991131652, 4868.172410690191, 10057.69826905194, 10083.59665613382, 10097.5456104474, 10102.19182465401, 10109.747256318651, 5459.235707001023, 5459.645123066988, 5464.9180262296095, 5468.808591699271, 5475.397706252309, 4382.101669157815, 4559.0, 4580.564846339593, 4936.333594964705, 4989.977054703777, 3878.3212937735784, 3966.960330396717, 3999.8800035998966, 4058.573849745734, 4090.2433049885867, 1282.8332316798942, 1287.665207046156, 1291.819145319656, 1305.3147097773744, 1307.8234438350892, 914.4376208631777, 923.9168474837297, 934.2245935873209, 939.8167357365248, 947.4173383369179, 5801.7389217485315, 5895.263092113486, 6015.699215039231, 6177.505799536027, 6237.407446292595, 927.4991504587447, 962.9359557688794, 996.3025881882711, 1016.8474728790741, 1078.1698092468912, 3521.9471707924404, 3726.105734623725, 3914.941275880864, 4090.3229675832767, 4132.235536425711, 10212.948935255276, 10889.965453281926, 10922.781544369069, 10924.2899211551, 10934.890651093547, 10885.782284354427, 10893.0, 10900.073493752961, 10906.018458338787, 10928.453577321103, 7816.6091695415, 8203.794905127419, 8236.094029656853, 8237.341012718967, 8344.207300306462, 5357.491038351351, 5954.791582294599, 5994.07091900756, 6177.660228687465, 6230.813075607739, 9851.704448866545, 10098.293119481566, 10130.746731331776, 10146.239032072772, 10159.187265018632, 9893.950530247303, 10183.490825458846, 10249.9487502562, 10376.16990640762, 10424.635137770141, 7097.574145551283, 7353.558786472829, 7522.0, 7540.283673051052, 7733.845323093394, 7432.442566807403, 8694.347923905605, 8704.434211776213, 8707.69523066715, 8709.608067637098, 7510.061242344707, 7645.541267523967, 7688.884666729891, 7809.062912450542, 7825.0, 7367.77896663101, 7551.622418879144, 7554.546727196386, 7571.0, 7635.923640763522, 7005.0893383859775, 7156.105486814148, 7163.713451461883, 7169.390601798906, 7170.390516805921, 4587.784237177084, 4968.3044373788425, 5040.672356296829, 5123.308353372396, 5139.306193663957, 5047.596192304681, 5108.131617624938, 5215.869603259949, 5241.423443421144, 5337.359516858002], "value": 4655.753214655814, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.45924635829664, 299.82759913049995, 302.8546297777067, 309.8961847780993, 318.6383454778828, 4318.301061836352, 4507.481639611446, 4931.679440836352, 5097.668651537648, 5270.156774916014, 5154.458781827911, 5197.922031169535, 5225.242339860727, 5238.5023422774775, 5253.553447956919, 3578.964210357889, 4028.9323329317754, 4037.555868854431, 4042.292598795199, 4063.8984025399304, 3572.69561133069, 3922.4900762900925, 4037.062210139135, 4039.555648878621, 4046.2803308593875, 3543.291341731664, 3909.6481316681493, 3965.9688480995005, 4030.9798451007705, 4094.5905409458965, 4035.737677050997, 4047.311956967293, 4051.534073581546, 4058.5738497457783, 4061.390791381287, 3083.72246497814, 3456.1705190754287, 3546.3261980223674, 3571.8749843755604, 3583.569971603424, 4703.977289765399, 4757.952420475785, 4765.451973023078, 4772.069446457942, 5237.869053273661, 4545.659075569312, 4546.204414227497, 4585.211767410679, 4593.83921562747, 4627.329037289574, 4744.881377965544, 5501.202325662756, 5508.411046807993, 5551.806361632228, 5557.026725891007, 3720.1815600567834, 3887.803391084057, 3893.824777885002, 3905.1213476967737, 3952.4071389291835, 5723.395854886058, 5734.788518429693, 5744.626599271034, 5749.0, 5758.337791154029, 3579.9439165446256, 3725.850965961331, 3935.901602459904, 3940.4680368150507, 3943.9014024649614, 3396.117009577528, 3837.059920319484, 3940.4877365942243, 3982.362821948532, 4017.4777278954134, 3922.9607703923166, 4069.0, 4432.024954510002, 4551.544845515422, 4650.18621741194, 5500.762328476061, 5510.752016159283, 5515.255440515479, 5538.144721518256, 5561.833145005657, 1091.0, 1188.989359044805, 1313.082898967499, 1326.5025615394227, 1326.8938484921184, 839.8152406470567, 870.9346798990101, 929.8279818233649, 937.5218638494417, 943.1228957069991, 4475.753833539186, 5029.974850125726, 5206.505381988705, 5303.920441193385, 5415.83752487426, 4978.427480839678, 5442.755076021588, 5691.8292451226525, 5741.483266506035, 5745.626534275259, 5136.332276803992, 5141.845744627667, 5153.273653326103, 5182.196759502283, 5378.7041712705395, 2450.6181469571875, 2800.159952014428, 2913.7960342776228, 2946.690597487233, 2957.807742496731, 4648.465426475931, 4926.482719314492, 5759.942400576025, 5922.318933322636, 5967.701614919323, 5261.921306132233, 5517.006938750983, 5904.291485021739, 5923.437273459014, 5926.259217597801, 5267.655893127521, 5480.424555421703, 5956.434138756811, 5967.850803729768, 6001.399860013964, 4924.8276310328965, 5002.0, 5442.0, 5734.30837902828, 5825.058345535477, 5651.2935883014625, 5654.688992105309, 5687.687177205128, 5688.317401911631, 5727.971360143172, 5777.306723193244, 5934.732937017759, 5942.881142377215, 5958.0, 6031.487323577545, 3563.429851223792, 3804.4483549885667, 3834.7183425861567, 3843.962130224768, 3898.0, 5192.922106168486, 5361.410244872983, 5361.785528578813, 5758.654480731169, 5787.56593255516, 4686.812527498998, 4766.904661906812, 4803.687760295569, 5602.915956260741, 5609.49514543693, 4879.4876537963, 5278.340207474066, 5526.861828454321, 5556.91664625039, 5584.441555844383, 4685.695429797052, 4839.370881785346, 5150.6137039721425, 5164.406093299319, 5364.624476286623, 4927.260910863399, 5521.585881058856, 5558.305211848519, 5561.360443549043, 5586.720663966704, 4572.954270457386, 4578.816847326069, 4830.478399304208, 5356.9911283268775, 5391.703456309784], "value": 4042.863420572145, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [750.5196674128555, 756.2097607999641, 795.6021989005501, 826.1366871619165, 877.6621000914646, 14812.555623331304, 15031.924840375783, 15518.534443966684, 15576.143312117843, 15879.682406351894, 13992.160470371784, 14331.42674293027, 14339.283035848192, 14342.784858227134, 14355.066920650177, 712.5154894671621, 881.4579033894157, 905.9762468410687, 907.9682211122612, 911.1344222988155, 739.8853177757456, 750.9474336796427, 884.7390019944132, 911.4622372800046, 920.1304766995192, 968.2254196642693, 972.1445128287097, 972.222222222223, 974.9317547771659, 974.9610015599368, 962.7400601837535, 965.1072757699135, 965.5751469353497, 966.2511553545988, 967.5936106835146, 561.5816216918381, 578.7887421091314, 630.2877748144567, 710.8045287545915, 716.6309350684402, 15104.471343503035, 15159.696806063926, 15165.696686066327, 15178.241087945558, 15191.392344306214, 13375.464981400732, 13678.0, 13724.0, 13779.311034448338, 14089.718205635932, 12349.75300493985, 12424.036462497961, 12507.3746312684, 12567.371631418484, 13033.087683862133, 4401.735895846259, 4456.442944631921, 4544.272916333404, 4569.0, 4661.114388266218, 104593.95406045919, 104866.47566762194, 105530.0, 105984.4700776499, 106088.0, 8870.689525866565, 10029.0, 10412.68761937143, 11021.283616564897, 11327.60353387644, 19626.21495140207, 19790.0, 20182.88994105338, 20305.28931487391, 20483.975801210177, 7528.134264559646, 7621.237876212445, 7670.386369090459, 7868.2131786822, 7929.167437419103, 4176.686748493875, 4207.8316867325575, 4310.741355518679, 4336.804843782037, 4462.665300102505, 3447.3622379859808, 3570.1610121621397, 3574.999000279926, 3579.1231148368297, 3805.276997370491, 717.3722992381666, 718.7664009197032, 719.093941633535, 734.7097896331027, 739.7928579997609, 602.7619090459244, 623.5759683415231, 636.3064259956669, 682.9726810927507, 706.0926709178783, 9517.524123793783, 9569.569369378394, 9634.566444510014, 9745.0, 9777.364471309342, 602.3795490644643, 605.5700452678648, 613.0865011133379, 630.2027934662656, 642.9646369449632, 4046.7571945683353, 4643.257078867365, 4672.742999135012, 4678.672492925531, 4723.433188017391, 113095.0, 162715.0, 163691.1815440939, 165652.0, 166307.0, 111625.0, 138882.0, 140743.3331000215, 141581.0, 144090.0, 97971.53042704507, 117532.0, 118013.0, 120570.39714801546, 120650.39674801745, 9274.860877086714, 9462.479563624067, 9753.951230243803, 9806.607735690492, 9832.60669573209, 53496.930061397776, 56841.43158568361, 58647.4135258642, 59411.1088333684, 59911.0, 81028.37943240984, 86510.56744716236, 87996.5600171995, 90405.0, 92306.0, 13001.0, 13088.149270297401, 13130.671733206364, 13310.600681979557, 13323.0, 14206.644833879236, 14322.355494002586, 14442.927785361426, 14611.780823287872, 14719.484818031746, 11419.657410277705, 13266.204027758366, 13513.864861351654, 13619.114757540729, 14141.787873182117, 13191.472341106248, 13370.331483425594, 13653.931730341683, 13698.246596437291, 14315.069520481135, 12900.41948112356, 13240.470381184645, 13305.800412994007, 13407.731845362843, 13519.986001050158, 10164.0, 10773.353598784099, 11268.83096753533, 11272.098232141245, 11526.366049867001, 10967.91201794641, 11387.772244554897, 11532.25040372373, 11602.825957610812, 11626.70933226676], "value": 7898.963259472809, "unit": "score"}], "name": "dromaeo_css", "value": 7320.1261472343895, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 114.0, 113.0, 114.0, 114.0, 113.0, 113.0, 113.0, 108.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 110.0, 107.0, 150.0, 112.0, 112.0, 112.0, 113.0, 141.0, 111.0], "value": 121.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 183.0, 184.0, 212.0, 254.0, 187.0, 184.0, 181.0, 246.0], "value": 200.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 59.0, 87.0, 64.0, 57.0, 59.0, 58.0, 83.0, 58.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 72.0, 90.0, 69.0, 67.0, 73.0, 93.0, 68.0, 69.0, 89.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 85.0, 84.0, 88.0, 85.0, 87.0, 85.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 92.0, 94.0, 94.0, 93.0, 93.0, 93.0, 92.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 80.0, 84.0, 83.0, 84.0, 86.0, 81.0, 83.0], "value": 82.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 52.0, 54.0, 53.0, 53.0, 53.0, 54.0, 53.0, 54.0, 54.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 76.0, 66.0, 69.0, 75.0, 70.0, 68.0, 69.0, 69.0, 70.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 86.0, 89.0, 88.0, 84.0, 88.0, 84.0, 87.0, 94.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 144.0, 171.0, 149.0, 143.0, 143.0, 145.0, 146.0, 183.0, 145.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 170.0, 176.0, 171.0, 169.0, 169.0, 174.0, 170.0, 170.0], "value": 170.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 69.0, 67.0, 68.0, 68.0, 68.0, 69.0, 68.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1441.8, "alertThreshold": 2.0}]} 11:01:57 INFO - Return code: 0 11:01:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:01:57 INFO - # TBPL SUCCESS # 11:01:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:01:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:01:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:01:57 INFO - 2016-10-17 11:01:57.295 screenresolution[1664:9754] starting screenresolution argv=screenresolution get 11:01:57 INFO - 2016-10-17 11:01:57.298 screenresolution[1664:9754] Display 0: 1600x1200x32@60 11:01:57 INFO - 2016-10-17 11:01:57.304 screenresolution[1665:9757] starting screenresolution argv=screenresolution list 11:01:57 INFO - Available Modes on Display 0 11:01:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:01:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:01:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:01:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:01:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:01:57 INFO - Intel Iris: 11:01:57 INFO - Chipset Model: Intel Iris 11:01:57 INFO - Type: GPU 11:01:57 INFO - Bus: Built-In 11:01:57 INFO - VRAM (Dynamic, Max): 1536 MB 11:01:57 INFO - Vendor: Intel (0x8086) 11:01:57 INFO - Device ID: 0x0a2e 11:01:57 INFO - Revision ID: 0x0009 11:01:57 INFO - Displays: 11:01:57 INFO - Display: 11:01:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:01:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:01:57 INFO - Display Serial Number: 1600x1200 60 11:01:57 INFO - Main Display: Yes 11:01:57 INFO - Mirror: Off 11:01:57 INFO - Online: Yes 11:01:57 INFO - Rotation: Supported 11:01:57 INFO - Return code: 0 11:01:57 INFO - Running post-action listener: _resource_record_post_action 11:01:57 INFO - [mozharness: 2016-10-17 18:01:57.542453Z] Finished run-tests step (success) 11:01:57 INFO - Running post-run listener: _resource_record_post_run 11:01:57 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 38679552; Write bytes: 707916800; Read time: 386; Write time: 1875 11:01:57 INFO - TinderboxPrint: CPU usage
53.9% 11:01:57 INFO - TinderboxPrint: I/O read bytes / time
38,679,552 / 386 11:01:57 INFO - TinderboxPrint: I/O write bytes / time
707,916,800 / 1,875 11:01:57 INFO - TinderboxPrint: CPU idle
2,241.0 (46.1%) 11:01:57 INFO - TinderboxPrint: CPU system
977.8 (20.1%) 11:01:57 INFO - TinderboxPrint: CPU user
1,639.0 (33.7%) 11:01:57 INFO - TinderboxPrint: Swap in / out
444,895,232 / 0 11:01:57 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399913472; Write bytes: 393729536; Read time: 29483; Write time: 721 11:01:57 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36594688; Write bytes: 297950720; Read time: 362; Write time: 1118 11:01:58 INFO - Running post-run listener: _upload_blobber_files 11:01:58 INFO - Blob upload gear active. 11:01:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:01:58 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 11:01:58 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'] 11:01:58 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 11:01:58 INFO - (blobuploader) - INFO - Open directory for files ... 11:01:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:01:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:01:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:01:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:01:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:01:59 INFO - (blobuploader) - INFO - Done attempting. 11:01:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:01:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:01:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:02:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:02:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:02:00 INFO - (blobuploader) - INFO - Done attempting. 11:02:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:02:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:02:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:02:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:02:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:02:02 INFO - (blobuploader) - INFO - Done attempting. 11:02:02 INFO - (blobuploader) - INFO - Iteration through files over. 11:02:02 INFO - Return code: 0 11:02:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:02:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:02:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/57f10ba4b3519fe62f4d2f24073983e1e0f0cf5e9a3d7f8e7dbe49cc95232ed4c58604b75630db5d8192e31ca96ca8157681da4f77d8f606d479d9c955e67c9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1613cfd642a697523154874191f255fc5c7bb0715e00e557db5843d503b1e2595764cca7a391aa6be760727d0351d6b0bb0d9b50281ff5599db691ba74c5b208", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/20dbfe432526b217e2635deb6a93a26292ded44b030d610a9328d9fc447b568b7ccd18e7c7748a1ccbf0cf5eb8f2709a47474be3107aae189ab5e42f408de029"} 11:02:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:02:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:02:02 INFO - Contents: 11:02:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/57f10ba4b3519fe62f4d2f24073983e1e0f0cf5e9a3d7f8e7dbe49cc95232ed4c58604b75630db5d8192e31ca96ca8157681da4f77d8f606d479d9c955e67c9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1613cfd642a697523154874191f255fc5c7bb0715e00e557db5843d503b1e2595764cca7a391aa6be760727d0351d6b0bb0d9b50281ff5599db691ba74c5b208", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/20dbfe432526b217e2635deb6a93a26292ded44b030d610a9328d9fc447b568b7ccd18e7c7748a1ccbf0cf5eb8f2709a47474be3107aae189ab5e42f408de029"} 11:02:02 INFO - Running post-run listener: copy_logs_to_upload_dir 11:02:02 INFO - Copying logs to upload dir... 11:02:02 INFO - mkdir: /builds/slave/test/build/upload/logs 11:02:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.425959 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-17 11:02:02.806608) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 11:02:02.810594) ========= 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.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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/57f10ba4b3519fe62f4d2f24073983e1e0f0cf5e9a3d7f8e7dbe49cc95232ed4c58604b75630db5d8192e31ca96ca8157681da4f77d8f606d479d9c955e67c9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1613cfd642a697523154874191f255fc5c7bb0715e00e557db5843d503b1e2595764cca7a391aa6be760727d0351d6b0bb0d9b50281ff5599db691ba74c5b208", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/20dbfe432526b217e2635deb6a93a26292ded44b030d610a9328d9fc447b568b7ccd18e7c7748a1ccbf0cf5eb8f2709a47474be3107aae189ab5e42f408de029"} build_url:https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010935 build_url: 'https://queue.taskcluster.net/v1/task/a6P6RtGjSKmMSiosVJZYfQ/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/57f10ba4b3519fe62f4d2f24073983e1e0f0cf5e9a3d7f8e7dbe49cc95232ed4c58604b75630db5d8192e31ca96ca8157681da4f77d8f606d479d9c955e67c9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1613cfd642a697523154874191f255fc5c7bb0715e00e557db5843d503b1e2595764cca7a391aa6be760727d0351d6b0bb0d9b50281ff5599db691ba74c5b208", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/20dbfe432526b217e2635deb6a93a26292ded44b030d610a9328d9fc447b568b7ccd18e7c7748a1ccbf0cf5eb8f2709a47474be3107aae189ab5e42f408de029"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 11:02:02.849724) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 11:02:02.850324) ========= 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.IAwKbIT4Hj/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.Ci2Rrjglo0/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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.005165 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 11:02:02.899943) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 11:02:02.900258) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 11:02:02.900586) ========= ========= Total master_lag: 0.19 =========