builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0181 starttime: 1482596453.85 results: success (0) buildid: 20161224062636 builduid: bf03869168b14236a3bae5a42b8ebce0 revision: 1deac793ef1dc655f2095e1f8d15deb32e2f666f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:53.847421) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:53.847875) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:53.848171) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:53.877815) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:53.878102) ========= 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.t3V6e64WGx/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.i9BZkc4oNO/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-12-24 08:20:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-12-24 08:20:54 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.165620 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:54.067505) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:54.067827) ========= 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.t3V6e64WGx/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.i9BZkc4oNO/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.026183 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:54.116210) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:54.116515) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1deac793ef1dc655f2095e1f8d15deb32e2f666f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1deac793ef1dc655f2095e1f8d15deb32e2f666f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.t3V6e64WGx/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.i9BZkc4oNO/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-12-24 08:20:54,210 truncating revision to first 12 chars 2016-12-24 08:20:54,210 Setting DEBUG logging. 2016-12-24 08:20:54,210 attempt 1/10 2016-12-24 08:20:54,210 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1deac793ef1d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-24 08:20:54,864 unpacking tar archive at: mozilla-aurora-1deac793ef1d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.905565 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:55.045234) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:55.045544) ========= script_repo_revision: 1deac793ef1dc655f2095e1f8d15deb32e2f666f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:55.046072) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:55.046398) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:55.066536) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-24 08:20:55.066953) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.t3V6e64WGx/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.i9BZkc4oNO/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:20:55 INFO - MultiFileLogger online at 20161224 08:20:55 in /builds/slave/test 08:20:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:20:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:20:55 INFO - {'append_to_log': False, 08:20:55 INFO - 'base_work_dir': '/builds/slave/test', 08:20:55 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:20:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:20:55 INFO - 'branch': 'Mozilla-Aurora', 08:20:55 INFO - 'buildbot_json_path': 'buildprops.json', 08:20:55 INFO - 'config_files': ('talos/mac_config.py',), 08:20:55 INFO - 'default_actions': ('clobber', 08:20:55 INFO - 'read-buildbot-config', 08:20:55 INFO - 'download-and-extract', 08:20:55 INFO - 'populate-webroot', 08:20:55 INFO - 'create-virtualenv', 08:20:55 INFO - 'install', 08:20:55 INFO - 'run-tests'), 08:20:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:20:55 INFO - 'download_minidump_stackwalk': True, 08:20:55 INFO - 'download_symbols': 'ondemand', 08:20:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:20:55 INFO - 'tooltool.py': '/tools/tooltool.py', 08:20:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:20:55 INFO - '/tools/misc-python/virtualenv.py')}, 08:20:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:20:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:20:55 INFO - 'installer_path': 'installer.exe', 08:20:55 INFO - 'log_level': 'info', 08:20:55 INFO - 'log_name': 'talos', 08:20:55 INFO - 'log_to_console': True, 08:20:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:20:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:20:55 INFO - 'opt_config_files': (), 08:20:55 INFO - 'pip_index': False, 08:20:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:20:55 INFO - 'cmd': ('bash', 08:20:55 INFO - '-c', 08:20:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:20:55 INFO - 'enabled': True, 08:20:55 INFO - 'halt_on_failure': False, 08:20:55 INFO - 'name': 'check_screen_resolution'},), 08:20:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:20:55 INFO - 'cmd': ('bash', 08:20:55 INFO - '-c', 08:20:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:20:55 INFO - 'enabled': True, 08:20:55 INFO - 'halt_on_failure': False, 08:20:55 INFO - 'name': 'check_screen_resolution'},), 08:20:55 INFO - 'run_cmd_checks_enabled': True, 08:20:55 INFO - 'sps_profile': False, 08:20:55 INFO - 'sps_profile_interval': 0, 08:20:55 INFO - 'suite': 'dromaeojs', 08:20:55 INFO - 'system_bits': '32', 08:20:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:20:55 INFO - 'title': 't-yosemite-r7-0181', 08:20:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:20:55 INFO - 'use_talos_json': True, 08:20:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:20:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:20:55 INFO - 'work_dir': 'build'} 08:20:55 INFO - [mozharness: 2016-12-24 16:20:55.275066Z] Running clobber step. 08:20:55 INFO - Running pre-action listener: _resource_record_pre_action 08:20:55 INFO - Running main action method: clobber 08:20:55 INFO - rmtree: /builds/slave/test/build 08:20:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:20:57 INFO - Running post-action listener: _resource_record_post_action 08:20:57 INFO - [mozharness: 2016-12-24 16:20:57.860991Z] Finished clobber step (success) 08:20:57 INFO - [mozharness: 2016-12-24 16:20:57.861123Z] Running read-buildbot-config step. 08:20:57 INFO - Running pre-action listener: _resource_record_pre_action 08:20:57 INFO - Running main action method: read_buildbot_config 08:20:57 INFO - Using buildbot properties: 08:20:57 INFO - { 08:20:57 INFO - "project": "", 08:20:57 INFO - "product": "firefox", 08:20:57 INFO - "script_repo_revision": "production", 08:20:57 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:20:57 INFO - "repository": "", 08:20:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:20:57 INFO - "buildid": "20161224062636", 08:20:57 INFO - "pgo_build": "False", 08:20:57 INFO - "basedir": "/builds/slave/test", 08:20:57 INFO - "buildnumber": 72, 08:20:57 INFO - "slavename": "t-yosemite-r7-0181", 08:20:57 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:20:57 INFO - "platform": "yosemite_r7", 08:20:57 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:20:57 INFO - "branch": "mozilla-aurora", 08:20:57 INFO - "repo_path": "releases/mozilla-aurora", 08:20:57 INFO - "revision": "1deac793ef1dc655f2095e1f8d15deb32e2f666f", 08:20:57 INFO - "stage_platform": "macosx64", 08:20:57 INFO - "builduid": "bf03869168b14236a3bae5a42b8ebce0", 08:20:57 INFO - "slavebuilddir": "test" 08:20:57 INFO - } 08:20:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:20:57 INFO - Running post-action listener: _resource_record_post_action 08:20:57 INFO - [mozharness: 2016-12-24 16:20:57.864271Z] Finished read-buildbot-config step (success) 08:20:57 INFO - [mozharness: 2016-12-24 16:20:57.864389Z] Running download-and-extract step. 08:20:57 INFO - Running pre-action listener: _resource_record_pre_action 08:20:57 INFO - Running main action method: download_and_extract 08:20:57 INFO - mkdir: /builds/slave/test/build/tests 08:20:57 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')]} 08:20:57 INFO - trying https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:20:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:20:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:20:59 INFO - Downloaded 1199 bytes. 08:20:59 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:20:59 INFO - Using the following test package requirements: 08:20:59 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:20:59 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:59 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:20:59 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:59 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:20:59 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:59 INFO - u'jsshell-mac.zip'], 08:20:59 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:59 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:20:59 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:20:59 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:59 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:20:59 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:59 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:20:59 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:59 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:20:59 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:59 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:20:59 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:20:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:20:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:20:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:20:59 INFO - Http code: 200 08:20:59 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 08:20:59 INFO - x-amz-cf-id: heAp88LCcurPlHmrSAe0LS6Ra_fWPaDEGh2EZgZeV7cN7yJ5QLy2Jw== 08:20:59 INFO - x-amz-version-id: TQq1GZh01Kpsv5NtxWuFFqyF6niuL.Jp 08:21:00 INFO - Expected file size: 16271146 08:21:00 INFO - Obtained file size: 16271146 08:21:06 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:21:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:21:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:21:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:21:07 INFO - Http code: 200 08:21:07 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 08:21:07 INFO - x-amz-cf-id: FFHHxEbETXY0A3-wvw-hkldxLI_5WiKhpMplIVA3_NGHMiWa0PxgYg== 08:21:07 INFO - x-amz-version-id: TQq1GZh01Kpsv5NtxWuFFqyF6niuL.Jp 08:21:07 INFO - Expected file size: 16271146 08:21:07 INFO - Obtained file size: 16271146 08:21:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:21:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:21:13 INFO - Fetch https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:21:14 INFO - Http code: 200 08:21:14 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 08:21:14 INFO - x-amz-cf-id: nJZEyORt3Yy5JLLbe4OYocbSjtF7ysXrBgd_DgCJeCNWm1zTKMV80Q== 08:21:14 INFO - x-amz-version-id: 0rMsfpbFcI3AgDct4IX0oAUle80vXfHz 08:21:14 INFO - Expected file size: 14409038 08:21:14 INFO - Obtained file size: 14409038 08:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:15 INFO - trying https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:21:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:21:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:21:18 INFO - Downloaded 109787364 bytes. 08:21:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:21:18 INFO - mkdir: /builds/slave/test/properties 08:21:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:21:18 INFO - Writing to file /builds/slave/test/properties/build_url 08:21:18 INFO - Contents: 08:21:18 INFO - build_url:https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:21:18 INFO - Running post-action listener: _resource_record_post_action 08:21:18 INFO - Running post-action listener: set_extra_try_arguments 08:21:18 INFO - [mozharness: 2016-12-24 16:21:18.943170Z] Finished download-and-extract step (success) 08:21:18 INFO - [mozharness: 2016-12-24 16:21:18.943323Z] Running populate-webroot step. 08:21:18 INFO - Running pre-action listener: _resource_record_pre_action 08:21:18 INFO - Running main action method: populate_webroot 08:21:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:21:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:21:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:21:18 INFO - '--tppagecycles', 08:21:18 INFO - '7'], 08:21:18 INFO - 'tests': ['tsvgm']}}, 08:21:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:21:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:21:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:21:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:21:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:21:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:21:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:18 INFO - 'talos_options': ['--disable-e10s'], 08:21:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:21:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:21:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:18 INFO - 'talos_options': ['--disable-e10s'], 08:21:18 INFO - 'tests': ['damp', 'tps']}, 08:21:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:18 INFO - 'tests': ['damp', 'tps']}, 08:21:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:21:18 INFO - 'tests': ['dromaeo_dom']}, 08:21:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:21:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:21:18 INFO - 'tests': ['basic_compositor_video']}, 08:21:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:21:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:21:18 INFO - 'tests': ['a11yr', 08:21:18 INFO - 'ts_paint', 08:21:18 INFO - 'tpaint', 08:21:18 INFO - 'sessionrestore', 08:21:18 INFO - 'sessionrestore_no_auto_restore', 08:21:18 INFO - 'tabpaint']}, 08:21:18 INFO - 'other-e10s': {'tests': ['a11yr', 08:21:18 INFO - 'ts_paint', 08:21:18 INFO - 'tpaint', 08:21:18 INFO - 'sessionrestore', 08:21:18 INFO - 'sessionrestore_no_auto_restore', 08:21:18 INFO - 'tabpaint']}, 08:21:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:21:18 INFO - 'ts_paint', 08:21:18 INFO - 'tpaint', 08:21:18 INFO - 'sessionrestore', 08:21:18 INFO - 'sessionrestore_no_auto_restore', 08:21:18 INFO - 'tabpaint']}, 08:21:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:21:18 INFO - 'ts_paint', 08:21:18 INFO - 'tpaint', 08:21:18 INFO - 'sessionrestore', 08:21:18 INFO - 'sessionrestore_no_auto_restore', 08:21:18 INFO - 'tabpaint']}, 08:21:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:21:18 INFO - 'tests': ['a11yr', 08:21:18 INFO - 'ts_paint', 08:21:18 INFO - 'tpaint', 08:21:18 INFO - 'sessionrestore', 08:21:18 INFO - 'sessionrestore_no_auto_restore', 08:21:18 INFO - 'tabpaint']}, 08:21:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:21:18 INFO - 'tests': ['a11yr', 08:21:18 INFO - 'ts_paint', 08:21:18 INFO - 'tpaint', 08:21:18 INFO - 'sessionrestore', 08:21:18 INFO - 'sessionrestore_no_auto_restore', 08:21:18 INFO - 'tabpaint']}, 08:21:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:21:18 INFO - 'tests': ['tsvgx', 08:21:18 INFO - 'tsvgr_opacity', 08:21:18 INFO - 'tart', 08:21:18 INFO - 'tscrollx', 08:21:18 INFO - 'cart']}, 08:21:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:21:18 INFO - 'tsvgr_opacity', 08:21:18 INFO - 'tart', 08:21:18 INFO - 'tscrollx', 08:21:18 INFO - 'cart']}, 08:21:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:18 INFO - 'talos_options': ['--disable-e10s'], 08:21:18 INFO - 'tests': ['tp5o']}, 08:21:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:18 INFO - 'tests': ['tp5o']}, 08:21:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:18 INFO - 'talos_options': ['--disable-e10s', 08:21:18 INFO - '--xperf_path', 08:21:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:21:18 INFO - 'tests': ['tp5n']}, 08:21:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:18 INFO - 'talos_options': ['--xperf_path', 08:21:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:21:18 INFO - 'tests': ['tp5n']}}, 08:21:18 INFO - 'talos.zip': {'path': '', 08:21:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:21:18 INFO - Running post-action listener: _resource_record_post_action 08:21:18 INFO - [mozharness: 2016-12-24 16:21:18.961820Z] Finished populate-webroot step (success) 08:21:18 INFO - [mozharness: 2016-12-24 16:21:18.961944Z] Running create-virtualenv step. 08:21:18 INFO - Running pre-action listener: _resource_record_pre_action 08:21:18 INFO - Running main action method: create_virtualenv 08:21:18 INFO - Creating virtualenv /builds/slave/test/build/venv 08:21:18 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 08:21:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:21:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:21:19 INFO - Using real prefix '/tools/python27' 08:21:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:21:19 INFO - Installing distribute.............................................................................................................................................................................................done. 08:21:22 INFO - Installing pip.................done. 08:21:22 INFO - Return code: 0 08:21:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:21:22 INFO - Reading from file tmpfile_stdout 08:21:22 INFO - Output received: 08:21:22 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) 08:21:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:21: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')]} 08:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21: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')]} 08:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105809490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d0c612c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10561dd30>, '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 0x105483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105615b20>, '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.t3V6e64WGx/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.i9BZkc4oNO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:21: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', 'pip>=1.5'] in /builds/slave/test/build 08:21: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 pip>=1.5 08:21:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3V6e64WGx/Render', 08:21:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:22 INFO - 'HOME': '/Users/cltbld', 08:21:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:22 INFO - 'LOGNAME': 'cltbld', 08:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:22 INFO - 'MOZ_NO_REMOTE': '1', 08:21:22 INFO - 'NO_EM_RESTART': '1', 08:21:22 INFO - 'PAGER': '/bin/cat', 08:21:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:22 INFO - 'PWD': '/builds/slave/test', 08:21:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:22 INFO - 'SHELL': '/bin/bash', 08:21:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9BZkc4oNO/Listeners', 08:21:22 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:21:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:22 INFO - 'USER': 'cltbld', 08:21:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:22 INFO - 'XPC_FLAGS': '0x0', 08:21:22 INFO - 'XPC_SERVICE_NAME': '0', 08:21:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:23 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 08:21:23 INFO - Cleaning up... 08:21:23 INFO - Return code: 0 08:21:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105809490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d0c612c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10561dd30>, '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 0x105483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105615b20>, '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.t3V6e64WGx/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.i9BZkc4oNO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:21:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:21:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3V6e64WGx/Render', 08:21:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:23 INFO - 'HOME': '/Users/cltbld', 08:21:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:23 INFO - 'LOGNAME': 'cltbld', 08:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:23 INFO - 'MOZ_NO_REMOTE': '1', 08:21:23 INFO - 'NO_EM_RESTART': '1', 08:21:23 INFO - 'PAGER': '/bin/cat', 08:21:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:23 INFO - 'PWD': '/builds/slave/test', 08:21:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:23 INFO - 'SHELL': '/bin/bash', 08:21:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9BZkc4oNO/Listeners', 08:21:23 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:21:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:23 INFO - 'USER': 'cltbld', 08:21:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:23 INFO - 'XPC_FLAGS': '0x0', 08:21:23 INFO - 'XPC_SERVICE_NAME': '0', 08:21:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:23 INFO - Downloading/unpacking psutil>=3.1.1 08:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:21:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:21:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:21:27 INFO - Installing collected packages: psutil 08:21:27 INFO - Running setup.py install for psutil 08:21:27 INFO - building 'psutil._psutil_osx' extension 08:21:27 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 08:21:28 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 08:21:28 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 08:21:28 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 08:21:28 INFO - building 'psutil._psutil_posix' extension 08:21:28 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 08:21:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:21:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:21:28 INFO - ^ 08:21:28 INFO - 1 warning generated. 08:21:28 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 08:21:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:21:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:21:28 INFO - Successfully installed psutil 08:21:28 INFO - Cleaning up... 08:21:28 INFO - Return code: 0 08:21:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105809490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d0c612c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10561dd30>, '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 0x105483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105615b20>, '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.t3V6e64WGx/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.i9BZkc4oNO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:21:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:21:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3V6e64WGx/Render', 08:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:28 INFO - 'HOME': '/Users/cltbld', 08:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:28 INFO - 'LOGNAME': 'cltbld', 08:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:28 INFO - 'MOZ_NO_REMOTE': '1', 08:21:28 INFO - 'NO_EM_RESTART': '1', 08:21:28 INFO - 'PAGER': '/bin/cat', 08:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:28 INFO - 'PWD': '/builds/slave/test', 08:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:28 INFO - 'SHELL': '/bin/bash', 08:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9BZkc4oNO/Listeners', 08:21:28 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:28 INFO - 'USER': 'cltbld', 08:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:28 INFO - 'XPC_FLAGS': '0x0', 08:21:28 INFO - 'XPC_SERVICE_NAME': '0', 08:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:21: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 08:21: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 08:21: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 08:21: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 08:21: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 08:21: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 08:21:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:21:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:21:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:21:31 INFO - Installing collected packages: mozsystemmonitor 08:21:31 INFO - Running setup.py install for mozsystemmonitor 08:21:31 INFO - Successfully installed mozsystemmonitor 08:21:31 INFO - Cleaning up... 08:21:31 INFO - Return code: 0 08:21:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105809490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d0c612c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10561dd30>, '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 0x105483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105615b20>, '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.t3V6e64WGx/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.i9BZkc4oNO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:21:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:21:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:21:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3V6e64WGx/Render', 08:21:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:31 INFO - 'HOME': '/Users/cltbld', 08:21:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:31 INFO - 'LOGNAME': 'cltbld', 08:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:31 INFO - 'MOZ_NO_REMOTE': '1', 08:21:31 INFO - 'NO_EM_RESTART': '1', 08:21:31 INFO - 'PAGER': '/bin/cat', 08:21:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:31 INFO - 'PWD': '/builds/slave/test', 08:21:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:31 INFO - 'SHELL': '/bin/bash', 08:21:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9BZkc4oNO/Listeners', 08:21:31 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:21:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:31 INFO - 'USER': 'cltbld', 08:21:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:31 INFO - 'XPC_FLAGS': '0x0', 08:21:31 INFO - 'XPC_SERVICE_NAME': '0', 08:21:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:32 INFO - Downloading/unpacking jsonschema==2.5.1 08:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:21:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:35 INFO - Downloading functools32-3.2.3-2.tar.gz 08:21:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:21:35 INFO - warning: no files found matching '*.txt' 08:21:35 INFO - no previously-included directories found matching 'build' 08:21:35 INFO - no previously-included directories found matching 'dist' 08:21:35 INFO - no previously-included directories found matching '.git*' 08:21:35 INFO - Installing collected packages: jsonschema, functools32 08:21:35 INFO - Running setup.py install for functools32 08:21:35 INFO - warning: no files found matching '*.txt' 08:21:35 INFO - no previously-included directories found matching 'build' 08:21:35 INFO - no previously-included directories found matching 'dist' 08:21:35 INFO - no previously-included directories found matching '.git*' 08:21:35 INFO - Successfully installed jsonschema functools32 08:21:35 INFO - Cleaning up... 08:21:35 INFO - Return code: 0 08:21:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:21: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')]} 08:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21: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')]} 08:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105809490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d0c612c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10561dd30>, '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 0x105483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105615b20>, '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.t3V6e64WGx/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.i9BZkc4oNO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:21:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3V6e64WGx/Render', 08:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:35 INFO - 'HOME': '/Users/cltbld', 08:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:35 INFO - 'LOGNAME': 'cltbld', 08:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:35 INFO - 'MOZ_NO_REMOTE': '1', 08:21:35 INFO - 'NO_EM_RESTART': '1', 08:21:35 INFO - 'PAGER': '/bin/cat', 08:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:35 INFO - 'PWD': '/builds/slave/test', 08:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:35 INFO - 'SHELL': '/bin/bash', 08:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9BZkc4oNO/Listeners', 08:21:35 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:35 INFO - 'USER': 'cltbld', 08:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:35 INFO - 'XPC_FLAGS': '0x0', 08:21:35 INFO - 'XPC_SERVICE_NAME': '0', 08:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:21:35 INFO - Cleaning up... 08:21:35 INFO - Return code: 0 08:21:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:21: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')]} 08:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21: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')]} 08:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105809490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d0c612c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10561dd30>, '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 0x105483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105615b20>, '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.t3V6e64WGx/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.i9BZkc4oNO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:21:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3V6e64WGx/Render', 08:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:35 INFO - 'HOME': '/Users/cltbld', 08:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:35 INFO - 'LOGNAME': 'cltbld', 08:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:35 INFO - 'MOZ_NO_REMOTE': '1', 08:21:35 INFO - 'NO_EM_RESTART': '1', 08:21:35 INFO - 'PAGER': '/bin/cat', 08:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:35 INFO - 'PWD': '/builds/slave/test', 08:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:35 INFO - 'SHELL': '/bin/bash', 08:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9BZkc4oNO/Listeners', 08:21:35 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:35 INFO - 'USER': 'cltbld', 08:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:35 INFO - 'XPC_FLAGS': '0x0', 08:21:35 INFO - 'XPC_SERVICE_NAME': '0', 08:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:35 INFO - Downloading/unpacking blobuploader==1.2.4 08:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:38 INFO - Downloading blobuploader-1.2.4.tar.gz 08:21:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:21:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:21:38 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 08:21:38 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 08:21:38 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 08:21:38 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 08:21:38 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 08:21:38 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 08:21:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:21:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:39 INFO - Downloading docopt-0.6.1.tar.gz 08:21:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:21:39 INFO - Installing collected packages: blobuploader, requests, docopt 08:21:39 INFO - Running setup.py install for blobuploader 08:21:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:21:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:21:39 INFO - Running setup.py install for requests 08:21:40 INFO - Running setup.py install for docopt 08:21:40 INFO - Successfully installed blobuploader requests docopt 08:21:40 INFO - Cleaning up... 08:21:40 INFO - Return code: 0 08:21:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:21: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')]} 08:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21: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')]} 08:21:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:40 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 0x105809490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d0c612c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10561dd30>, '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 0x105483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105615b20>, '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.t3V6e64WGx/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.i9BZkc4oNO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:21:40 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 08:21:40 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 08:21:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3V6e64WGx/Render', 08:21:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:40 INFO - 'HOME': '/Users/cltbld', 08:21:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:40 INFO - 'LOGNAME': 'cltbld', 08:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:40 INFO - 'MOZ_NO_REMOTE': '1', 08:21:40 INFO - 'NO_EM_RESTART': '1', 08:21:40 INFO - 'PAGER': '/bin/cat', 08:21:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:40 INFO - 'PWD': '/builds/slave/test', 08:21:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:40 INFO - 'SHELL': '/bin/bash', 08:21:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9BZkc4oNO/Listeners', 08:21:40 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:21:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:40 INFO - 'USER': 'cltbld', 08:21:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:40 INFO - 'XPC_FLAGS': '0x0', 08:21:40 INFO - 'XPC_SERVICE_NAME': '0', 08:21:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:21:40 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-9AQqvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:21:41 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-eFCyaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:21:41 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-bN38F1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:21:41 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-xc2f7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:21:41 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-uvMe9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:21:41 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-ZuKGwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:21:41 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-U_ycFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:21:41 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-L0uwbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:21:41 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-m2McjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:21:41 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-wiXQ1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:21:41 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-0Aff68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:21:42 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-_JUbeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:21:42 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-sRkkq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:21:42 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-L4Td8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:21:42 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-KENE1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:21:42 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-ilJIC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:21:42 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-D_DcQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:21:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:21:42 INFO - Running setup.py install for manifestparser 08:21:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:21:42 INFO - Running setup.py install for mozcrash 08:21:43 INFO - Running setup.py install for mozdebug 08:21:43 INFO - Running setup.py install for mozdevice 08:21:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:21:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:21:43 INFO - Running setup.py install for mozfile 08:21:43 INFO - Running setup.py install for mozhttpd 08:21:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:21:43 INFO - Running setup.py install for mozinfo 08:21:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:21:43 INFO - Running setup.py install for mozInstall 08:21:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:21:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:21:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:21:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:21:43 INFO - Running setup.py install for mozleak 08:21:44 INFO - Running setup.py install for mozlog 08:21:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:21:44 INFO - Running setup.py install for moznetwork 08:21:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:21:44 INFO - Running setup.py install for mozprocess 08:21:44 INFO - Running setup.py install for mozprofile 08:21:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:21:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:21:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:21:44 INFO - Running setup.py install for mozrunner 08:21:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:21:44 INFO - Running setup.py install for mozscreenshot 08:21:44 INFO - Running setup.py install for moztest 08:21:45 INFO - Running setup.py install for mozversion 08:21:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:21:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:21:45 INFO - Cleaning up... 08:21:45 INFO - Return code: 0 08:21:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:45 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 0x105809490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d0c612c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10561dd30>, '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 0x105483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105615b20>, '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.t3V6e64WGx/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.i9BZkc4oNO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:21:45 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 08:21:45 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 08:21:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3V6e64WGx/Render', 08:21:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:45 INFO - 'HOME': '/Users/cltbld', 08:21:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:45 INFO - 'LOGNAME': 'cltbld', 08:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:45 INFO - 'MOZ_NO_REMOTE': '1', 08:21:45 INFO - 'NO_EM_RESTART': '1', 08:21:45 INFO - 'PAGER': '/bin/cat', 08:21:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:45 INFO - 'PWD': '/builds/slave/test', 08:21:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:45 INFO - 'SHELL': '/bin/bash', 08:21:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9BZkc4oNO/Listeners', 08:21:45 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:21:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:45 INFO - 'USER': 'cltbld', 08:21:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:45 INFO - 'XPC_FLAGS': '0x0', 08:21:45 INFO - 'XPC_SERVICE_NAME': '0', 08:21:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:21:45 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-j0pYZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:21:45 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)) 08:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:21:45 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-qzVckj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:21:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:21:45 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-bb_ist-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:21:45 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)) 08:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:21:45 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-9x1noj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:21:45 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)) 08:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:21:45 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-l201pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:21:46 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)) 08:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:21:46 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-a7zQ3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:21:46 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)) 08:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:21:46 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-VajLSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:21:46 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)) 08:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:21:46 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-5jBZX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:21:46 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)) 08:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:21:46 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-k3PWUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:21:46 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)) 08:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:21:46 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-KfF2CZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:21:46 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)) 08:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:21:46 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-3Oa9YQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:21:46 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)) 08:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:21:46 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-17gqEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:21:46 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)) 08:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:21:46 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-uuia5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:21:46 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)) 08:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:21:46 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-xd45n_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:21:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:21:47 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-jyPX08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:21:47 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)) 08:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:21:47 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-XG1zdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:21:47 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)) 08:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:21:47 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-If5Seb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:21:47 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)) 08:21:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:21:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:21:47 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)) 08:21:47 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)) 08:21:47 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)) 08:21:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:21:47 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 08:21:47 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 08:21:47 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 08:21:47 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 08:21:47 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 08:21:47 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 08:21:49 INFO - Downloading blessings-1.6.tar.gz 08:21:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:21:50 INFO - Installing collected packages: blessings 08:21:50 INFO - Running setup.py install for blessings 08:21:50 INFO - Successfully installed blessings 08:21:50 INFO - Cleaning up... 08:21:50 INFO - Return code: 0 08:21:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:21:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:21:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:21:50 INFO - Reading from file tmpfile_stdout 08:21:50 INFO - Current package versions: 08:21:50 INFO - blessings == 1.6 08:21:50 INFO - blobuploader == 1.2.4 08:21:50 INFO - docopt == 0.6.1 08:21:50 INFO - functools32 == 3.2.3-2 08:21:50 INFO - jsonschema == 2.5.1 08:21:50 INFO - manifestparser == 1.1 08:21:50 INFO - mozInstall == 1.12 08:21:50 INFO - mozcrash == 1.0 08:21:50 INFO - mozdebug == 0.1 08:21:50 INFO - mozdevice == 0.48 08:21:50 INFO - mozfile == 1.2 08:21:50 INFO - mozhttpd == 0.7 08:21:50 INFO - mozinfo == 0.9 08:21:50 INFO - mozleak == 0.1 08:21:50 INFO - mozlog == 3.4 08:21:50 INFO - moznetwork == 0.27 08:21:50 INFO - mozprocess == 0.23 08:21:50 INFO - mozprofile == 0.28 08:21:50 INFO - mozrunner == 6.13 08:21:50 INFO - mozscreenshot == 0.1 08:21:50 INFO - mozsystemmonitor == 0.3 08:21:50 INFO - moztest == 0.7 08:21:50 INFO - mozversion == 1.4 08:21:50 INFO - psutil == 3.1.1 08:21:50 INFO - requests == 1.2.3 08:21:50 INFO - wsgiref == 0.1.2 08:21:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:50 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 0x105809490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d0c612c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10561dd30>, '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 0x105483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105615b20>, '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.t3V6e64WGx/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.i9BZkc4oNO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:21:50 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 08:21:50 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 08:21:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3V6e64WGx/Render', 08:21:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:50 INFO - 'HOME': '/Users/cltbld', 08:21:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:50 INFO - 'LOGNAME': 'cltbld', 08:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:50 INFO - 'MOZ_NO_REMOTE': '1', 08:21:50 INFO - 'NO_EM_RESTART': '1', 08:21:50 INFO - 'PAGER': '/bin/cat', 08:21:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:50 INFO - 'PWD': '/builds/slave/test', 08:21:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:50 INFO - 'SHELL': '/bin/bash', 08:21:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9BZkc4oNO/Listeners', 08:21:50 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:21:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:50 INFO - 'USER': 'cltbld', 08:21:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:50 INFO - 'XPC_FLAGS': '0x0', 08:21:50 INFO - 'XPC_SERVICE_NAME': '0', 08:21:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:50 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)) 08:21:50 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)) 08:21:50 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)) 08:21:50 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)) 08:21:50 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)) 08:21:50 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)) 08:21:50 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)) 08:21:50 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)) 08:21:50 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)) 08:21:50 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)) 08:21:50 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)) 08:21:50 INFO - Cleaning up... 08:21:50 INFO - Return code: 0 08:21:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:50 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 0x105809490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d0c612c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10561dd30>, '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 0x105483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105615b20>, '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.t3V6e64WGx/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.i9BZkc4oNO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:21:50 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 08:21:50 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 08:21:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3V6e64WGx/Render', 08:21:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:50 INFO - 'HOME': '/Users/cltbld', 08:21:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:50 INFO - 'LOGNAME': 'cltbld', 08:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:50 INFO - 'MOZ_NO_REMOTE': '1', 08:21:50 INFO - 'NO_EM_RESTART': '1', 08:21:50 INFO - 'PAGER': '/bin/cat', 08:21:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:50 INFO - 'PWD': '/builds/slave/test', 08:21:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:50 INFO - 'SHELL': '/bin/bash', 08:21:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9BZkc4oNO/Listeners', 08:21:50 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:21:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:50 INFO - 'USER': 'cltbld', 08:21:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:50 INFO - 'XPC_FLAGS': '0x0', 08:21:50 INFO - 'XPC_SERVICE_NAME': '0', 08:21:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:21:51 INFO - Cleaning up... 08:21:51 INFO - Return code: 0 08:21:51 INFO - Running post-action listener: _resource_record_post_action 08:21:51 INFO - Running post-action listener: _start_resource_monitoring 08:21:51 INFO - Starting resource monitoring. 08:21:51 INFO - [mozharness: 2016-12-24 16:21:51.174705Z] Finished create-virtualenv step (success) 08:21:51 INFO - [mozharness: 2016-12-24 16:21:51.175701Z] Running install step. 08:21:51 INFO - Running pre-action listener: _resource_record_pre_action 08:21:51 INFO - Running main action method: install 08:21:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:21:51 INFO - Reading from file tmpfile_stdout 08:21:51 INFO - Detecting whether we're running mozinstall >=1.0... 08:21:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:21:51 INFO - Reading from file tmpfile_stdout 08:21:51 INFO - Output received: 08:21:51 INFO - Usage: mozinstall [options] installer 08:21:51 INFO - Options: 08:21:51 INFO - -h, --help show this help message and exit 08:21:51 INFO - -d DEST, --destination=DEST 08:21:51 INFO - Directory to install application into. [default: 08:21:51 INFO - "/builds/slave/test"] 08:21:51 INFO - --app=APP Application being installed. [default: firefox] 08:21:51 INFO - mkdir: /builds/slave/test/build/application 08:21:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:22:22 INFO - Reading from file tmpfile_stdout 08:22:22 INFO - Output received: 08:22:22 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:22:22 INFO - Running post-action listener: _resource_record_post_action 08:22:22 INFO - [mozharness: 2016-12-24 16:22:22.208710Z] Finished install step (success) 08:22:22 INFO - [mozharness: 2016-12-24 16:22:22.208841Z] Running run-tests step. 08:22:22 INFO - Running pre-action listener: _resource_record_pre_action 08:22:22 INFO - Running main action method: run_tests 08:22:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:22:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:22:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:22:22 INFO - 2016-12-24 08:22:22.223 screenresolution[1651:8203] starting screenresolution argv=screenresolution get 08:22:22 INFO - 2016-12-24 08:22:22.225 screenresolution[1651:8203] Display 0: 1600x1200x32@60 08:22:22 INFO - 2016-12-24 08:22:22.231 screenresolution[1652:8207] starting screenresolution argv=screenresolution list 08:22:22 INFO - Available Modes on Display 0 08:22:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:22:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:22:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:22:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:22:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:22:22 INFO - Intel Iris: 08:22:22 INFO - Chipset Model: Intel Iris 08:22:22 INFO - Type: GPU 08:22:22 INFO - Bus: Built-In 08:22:22 INFO - VRAM (Dynamic, Max): 1536 MB 08:22:22 INFO - Vendor: Intel (0x8086) 08:22:22 INFO - Device ID: 0x0a2e 08:22:22 INFO - Revision ID: 0x0009 08:22:22 INFO - Displays: 08:22:22 INFO - Display: 08:22:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:22:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:22:22 INFO - Display Serial Number: 1600x1200 60 08:22:22 INFO - Main Display: Yes 08:22:22 INFO - Mirror: Off 08:22:22 INFO - Online: Yes 08:22:22 INFO - Rotation: Supported 08:22:22 INFO - Return code: 0 08:22:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:22:22 INFO - Python 2.7.3 08:22:22 INFO - Return code: 0 08:22:22 INFO - grabbing minidump binary from tooltool 08:22: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')]} 08:22:22 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 0x10561dd30>, '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 0x105483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105615b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:22:22 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 08:22:22 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 08:22:22 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 08:22:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:22:22 INFO - Return code: 0 08:22:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:22:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:22:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:22:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:22:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:22:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 08:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0181 --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 08:22:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3V6e64WGx/Render', 08:22:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:22 INFO - 'HOME': '/Users/cltbld', 08:22:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:22 INFO - 'LOGNAME': 'cltbld', 08:22:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:22:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:22 INFO - 'MOZ_NO_REMOTE': '1', 08:22:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:22:22 INFO - 'NO_EM_RESTART': '1', 08:22:22 INFO - 'PAGER': '/bin/cat', 08:22:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:22 INFO - 'PWD': '/builds/slave/test', 08:22:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:22:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:22 INFO - 'SHELL': '/bin/bash', 08:22:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9BZkc4oNO/Listeners', 08:22:22 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:22:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:22 INFO - 'USER': 'cltbld', 08:22:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:22 INFO - 'XPC_FLAGS': '0x0', 08:22:22 INFO - 'XPC_SERVICE_NAME': '0', 08:22:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 08:22:22 INFO - mozversion application_buildid: 20161224062636 08:22:22 INFO - mozversion application_changeset: 1deac793ef1dc655f2095e1f8d15deb32e2f666f 08:22:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:22:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:22:22 INFO - mozversion application_name: Firefox 08:22:22 INFO - mozversion application_remotingname: firefox-dev 08:22:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:22:22 INFO - mozversion application_vendor: Mozilla 08:22:22 INFO - mozversion application_version: 52.0a2 08:22:22 INFO - mozversion platform_buildid: 20161224062636 08:22:22 INFO - mozversion platform_changeset: 1deac793ef1dc655f2095e1f8d15deb32e2f666f 08:22:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:22:22 INFO - mozversion platform_version: 52.0a2 08:22:22 INFO - using testdate: 1482596542 08:22:22 INFO - actual date: 1482596542 08:22:22 INFO - starting webserver on 'localhost:49250' 08:22:22 INFO - SUITE-START | Running 2 tests 08:22:22 INFO - TEST-START | dromaeo_css 08:22:22 INFO - operating with platform_type : mac_ 08:22:22 INFO - Initialising browser for dromaeo_css test... 08:22:22 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpSFJfno/profile http://localhost:49250/getInfo.html) 08:22:25 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 08:22:25 INFO - PROCESS | 1662 | colorDepth:24 08:22:25 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 08:22:25 INFO - PROCESS | 1662 | __metrics 08:22:25 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:22:25 INFO - PROCESS | 1662 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:22:26 INFO - TEST-INFO | 1662: exit 0 08:22:26 INFO - Browser initialized. 08:22:26 INFO - Running cycle 1/1 for dromaeo_css test... 08:22:26 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpSFJfno/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:22:38 INFO - PROCESS | 1665 | RSS: Main: 222924800 08:22:38 INFO - PROCESS | 1665 | 08:23:30 INFO - PROCESS | 1665 | 1482596610545 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] 08:25:43 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49250/tests/dromaeo/cssquery-ext.html) 08:25:45 INFO - PROCESS | 1665 | RSS: Main: 417255424 08:25:45 INFO - PROCESS | 1665 | 08:28:49 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-ext.html (next: http://localhost:49250/tests/dromaeo/cssquery-jquery.html) 08:28:49 INFO - PROCESS | 1665 | RSS: Main: 350736384 08:28:49 INFO - PROCESS | 1665 | 08:31:51 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49250/tests/dromaeo/cssquery-mootools.html) 08:31:52 INFO - PROCESS | 1665 | RSS: Main: 324927488 08:31:52 INFO - PROCESS | 1665 | 08:34:54 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49250/tests/dromaeo/cssquery-prototype.html) 08:34:55 INFO - PROCESS | 1665 | RSS: Main: 325963776 08:34:55 INFO - PROCESS | 1665 | 08:37:58 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49250/tests/dromaeo/cssquery-yui.html) 08:37:59 INFO - PROCESS | 1665 | RSS: Main: 311414784 08:37:59 INFO - PROCESS | 1665 | 08:41:02 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-yui.html (next: http://localhost:49250/tests/dromaeo/cssquery-dojo.html) 08:41:02 INFO - PROCESS | 1665 | RSS: Main: 395325440 08:41:02 INFO - PROCESS | 1665 | 08:41:02 INFO - PROCESS | 1665 | __start_tp_report 08:41:02 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 08:41:02 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 08:41:02 INFO - PROCESS | 1665 | |i|pagename|runs| 08:41:02 INFO - PROCESS | 1665 | |0;dojo.html;2848.3021659693372;3285.884994025209;3287.506873968906;3287.8356082195883;3352.5306457096012;5583.05088135017;5607.859803504914;5635.957347890633;5640.125780504023;5649.519790817781;5574.832755017356;5663.150527420889;5675.34733505647;5678.375378708339;5736.426357364261;5117.692938423688;5123.5388815006645;5154.175331946889;5185.533302002819;5198.818041368553;5119.846404607868;5120.743962801864;5148.7940482380845;5189.662671926313;5268.104422248208;5168.147255702805;5183.2743415921805;5191.402988656297;5206.557442617363;5210.947890521084;5182.37811462625;5186.7925282988635;5197.948020519784;5199.610029247784;5215.452377500384;5067.49325067494;5178.249153872667;5210.47895210476;5244.239585260116;5261.368635763694;5525.419830917776;5567.832965011056;5595.38450770415;5680.460356266148;5700.37295897451;6108;6109.799438327889;6112.908306375408;6120.571559990803;6122.387761223887;6225.782097626607;6235.314115447342;6237.656928868911;6246.187995560548;6278.87442251157;5739.225204597409;5746.942530574641;5750.7987220447085;5759.913601295984;5796.94203057964;6141.140240366352;6172.351903050205;6234.532410069263;6248.4376406123665;6251.531135164881;6047.637141771508;6059.515238780887;6075.33171351151;6075.453209211192;6090.482309003695;5584.888302234014;6041.305249896231;6191.876162476725;6225.782097626561;6226.875462490725;5846.473817356458;5918.615290006136;6075;6085.4523092921845;6138.232720909887;5231.686098834082;5840.970795145997;5944.108383742387;6035.698215089228;6035.7283922223605;4756.381670382828;4762.618990480753;4765.7140571565815;4777.617790576745;4817.590504807131;4162.105147393325;4335.176316499918;4398.494173170063;4405.7797110144365;4414.867553973386;5352.57179425645;5403.540699040547;5408.945255675184;5417.5395091416885;5418.83743487696;5867.765289388377;5868.823935281948;5877.706114694248;5881.05903055509;5881.9705901470215;5385.784568617211;5388.838334849961;5389.245505629215;5394.433584473652;5398.514133727984;5052.873678158002;5100.6684565503865;5114.43741188469;5116.974415127975;5119.718415487109;6247.937520624827;6258.249010118815;6260;6260.154879091265;6271.3415091414745;6116.296625887901;6139.369480960463;6142.40669208518;6145.064304743992;6155.107509411021;6254.8123556293385;6255.624662520263;6261.530385221035;6268.27914789805;6271.686415679289;4494.77526123699;6061.909071363845;6064.363241859718;6066.211392518944;6093.725782339894;6115.879294474606;6121.755129794758;6135;6162.291336496181;6174.753009879554;4729.243321068612;5879.575087860515;6021.849453763516;6049.909251361145;6060.303065147565;5971;6011.7595296187665;6024.638521688713;6026.246719160105;6035.064564992434;5863.149843272787;5936.317323507852;5963.880722385615;5965.194698715618;6005.879882402415;6072.392760724069;6075.635461872302;6106.084087386927;6119.632822030692;6126.264848218242;5835.649861008353;5944.138099975566;5949.702514874152;5976.551758618034;5988.371221021905 08:41:02 INFO - PROCESS | 1665 | |1;ext.html;510.8978204359128;521.9869503262419;538.4830562659846;541.515343767328;555.9722013899307;37993.81003094984;39124.21751564967;39223.80388098059;39279.60720392795;39335.40996885042;39827.60172398275;40380.59619403805;40382.39426408606;40427.39358909619;40442;8274.420790544635;9095.954520227406;9235.215006724437;9260.073992600754;9275.628974841033;8300.252977232049;8489.405741598091;8492.320614350869;8497.830043399128;10888.564457421693;11007.944960275187;11219.887801122006;11225.494852731606;11232.663020109369;11251.493682784294;9314.906850931471;11221.214514983954;11234;11234.550617975272;11241.381724005176;7068.717251309941;8458.98492180936;10838.40388778617;11035.724106897393;11095;38189.618103819164;38518.42222366667;38523;38593.03517412059;38611.420828687595;3243.6269828969735;3308.6195087564756;3324.8670053197843;3533.1697051193205;3824.9043773905596;27675.169744907686;27680.723192768015;27733;27959.58060629092;28144.718552814415;3049.786514943955;3057.480228361184;3255.4953982132806;3317.717993970515;3322.069820450278;439045;497656;497718;499218;499334;59726.402735972086;60088.69955650194;60110.699446503364;60212.69893650504;60236.69881650564;62205;62314.75370492565;62443.68778156171;62495;62554.37445625485;13164.473421063241;15977.760333595006;15997.28012239452;16039.919800400923;16044.75932861008;12246.44890979908;12318.876811231952;12319.014478841671;12326;12340.321282329558;15486.5354039379;15745.606359841096;15852.52442426729;15901.443449479213;18145.546361341072;6564.901526477107;6970.302969703091;7925.365970722329;8097.514149150952;8124.431289809775;874.9081346458657;938.1697197979837;944.4616568555857;1010.5503051142172;1015.2487159501916;28226.576601350585;28953.85523072371;29210.269743256587;29213;29923.401531969237;1022.483645758887;1090.7654854206385;1157.2073129906041;1157.2940506291156;1158.5539567266494;12890.613281601567;13557.661058473419;14669.853301467063;15033.473828416172;15346.616334591501;683529;899915;902165;902464;902544;47172.7641361791;47700.04599908052;47937.760311198224;52616.736916315174;54592;1358.7282543491342;1379.9586012419443;1398.3357904995046;1400.9299535023413;1407.444059596474;28715.856420717762;28887.277818054717;29244;29836.254093647833;29952.400951981275;47654;48069.03861922812;48248.03503929832;48357.7910552239;48382;510820;838302;851771.7411412903;852127;856944;41532;42069;42378.36432453454;42557.93605159772;42570.57429425666;36400;45189.870253243935;45474.63576092722;45720;47406.76296618495;31581.89463368863;36007.27985440329;36795.63204368029;37595;37903.81048094742;37279.81360093182;37563.81218093892;38055.80972095122;38394;40518.1896362077;36161;36623.63376366202;37129.071773205884;37313.813430933755;37515.06212344604;31570.842145789124;33657;33802.3239535203;33846.830765847;33950.49074263938;23896.88051559731;25201.74798251994;25241.621375679013;25744.742552574233;25753.484930300914 08:41:02 INFO - PROCESS | 1665 | |2;jquery.html;351.52368540627435;366.36070057749225;374.9737518373713;384.57504457574373;404.6863680647498;14504.347304371302;14650.487232946849;15402.61493462664;19705.310314138973;20208.59582808343;13955.162690238592;15458.922705386485;18950.905245473787;19528.21887124513;19615.509612259702;5550.139728342114;5890.764369425217;5893.116032595102;5893.44012318829;5893.440123188312;5649.23735295734;5767.855803604902;5905.291365036202;5975.372585878486;5989.0417533194695;5882.294124705041;5920.141579470974;5921.61509501881;5931.88136237275;5943.524518038546;5607.915881261784;5696.575856035991;5770.7691692332255;5774.740136693859;5779.393163717791;4927.507249275044;5058.747062646853;5066.189409694432;5074.238864170367;5074.797008119671;16409.343626254937;21336.786632133637;21420.25029123989;22300.776992230032;24057;2224.3882932193615;2299.011425087212;2390.725066617344;2392.868392238426;2563;14440.783388249185;15292.929494935359;15295.923520382437;15322.15728134952;15419.997700149566;1398.3847107272868;1560.5474412420385;1646.538969088657;1696.236693487923;1830.7986121526621;113137;113389.43994585157;115016.42491787487;115504;122031.3898430511;9035.909640903506;11423.771524569462;11500.022498087756;11534.59628912984;11718.88281117178;14629.414823407158;14642;14650.706985860435;14654.706905861824;14674;8275.04836943747;8340.958295208486;8379.539125348043;8386.203310685476;8488.575571221538;6257.812265632038;7553.942448057276;7941.6029198539845;8089.393295502743;8419.07390187079;3636.636336366342;3782.394816829294;4484.282514797616;4513.052259025641;4601.608863246594;1596.8562829345183;1854.0173707934816;1912.3402426162952;1917.7027560728109;1921.7982111878052;821.8726097454904;850.5704619167404;876.9736907892774;880.7445840706251;902.4134312696668;19041.333553325843;20021.499462513555;20044.69932951007;20094.598108037757;20097.19611215564;902.6254104546671;915.6154415145655;918.0079228075093;979.6277414582413;985.7486340983004;10347.327423717434;10853.837192442119;10900.672979810459;10920.235583509078;10959.616413425492;587659;611905;613800;637066.8146659237;638339;97061;101942;104606;108660;136308;911.334725650279;933.5565606337071;944.6127087894068;952.8094381123803;957.9712608621752;17914.283428662708;18606.44180674582;19007;19008.619827603095;19132.23471061142;40109;51309;52310.47689523208;52971;54093;55062;73022;74609.25390746241;76427;79943.60028199822;7939.761807145795;7979.5611241382285;8091.543522166066;8180.5909704515725;8268.83462330762;11584.362860042442;12390.3185324808;12544.372781361079;12788.424520896397;13003.869961300266;9196.908030919605;9711.980242074764;10019.54912028975;10661.040506354466;10694.358338499715;10527.263091583516;10699.94650026745;10704.036636702735;10740;10768.51541680642;10435.895641043797;10749.462526873469;10788.892111079103;10866.61966831157;11033;7879.684812607432;7904;8192.54940978252;8274.255317021498;8275.95862020686;9622.230221582116;10546.947265263625;10766.892331076902;10953.014228719454;11107.555697771999 08:41:02 INFO - PROCESS | 1665 | |3;mootools.html;1338.5448947357897;1440.2222799688168;1581.936722531099;1779.5373202967226;1825.8813177143484;7983.680652773889;8463.492190468569;8472.195141461567;8475.830483390344;8492.830143397136;7390.3718183954425;7576.507527010741;7595.240475952402;7723.806904827382;8263.504189748619;2101.821345185661;2133.072113630568;2137.8824164670937;2228.43174990378;2317.188983855654;2349.142562964514;2359.6578496118054;2363.2673871100014;2367.1951536477595;2369.087901158058;1971.221367559813;2282.7146606674164;2302.896369663361;2336.2874323331375;2338.7193536775526;2302.1366987379733;2304.2626359564947;2328.359701082216;2334.4280651240383;2336.4742932839977;2016.9596608067902;2341.30931375243;2358.7759162879497;2362.4566349739657;2363.279199844047;8246.216609422096;8378.204070613281;8381.916180838174;8447.746567602982;8490.10853860342;4953.975230123862;5034.647574669774;5199.506046925536;5210.244514545369;5216.504432078947;2840.9431811363866;3383.712384447325;3433.1760377509468;3566.286742651454;3580.0691820126694;945.352433582991;1001.697792869267;1057.4924036462464;1079.2445288298222;1091.4597274349248;10868.510917008754;10966.177536684781;10971.122310215163;11003.559857605685;11028.724281893017;4463.196624607602;4600.217962946307;4658.697184682985;4667.13657973276;4732.739699316502;9394.577244024036;9400.529973501298;9402.623895044122;9403.106704863027;9430.858537121812;5200.453952335026;5202.3497062867145;5204.115300398941;5209.947900521022;5210.583153347723;4556.840510582114;4730.905381892343;4749.287606858929;4775.450823155382;4788.664793464424;3579.785212887183;3688.4098544232793;3690.704743620504;3864.381698928158;3958.6437220650278;1150.3902931446346;1152.9769404611861;1159.2406973432414;1224.5652793258264;1264.5321231144535;739.0134170881826;822.658596682382;872.3631748823268;881.1012766977647;902.0618556701089;5484.040292948718;5566.582506312043;5652.943470565242;5830.125481177773;5972.880542389128;910.9316801239801;924.200566509964;950.2967803825258;1021.581151727788;1022.1669339488293;3595.7303202259936;3667.94498082529;3706.2031663192543;3717.8698745544316;3826.7703937763267;9882.65410710636;10073.194144468425;10096.444695541815;10171.54228059739;10317;9536.475493847765;9991.300608957303;10013.599456021677;10018.448985305733;10046.246531510165;7577.242275772379;7800.453968222283;7811.531308121531;7815.335696465864;7820.687172513036;5529.529989950738;5547.805826796042;5567.580267031881;5723.456271654186;5760.164776107525;9473.052694730748;9495.762605934908;9498.19265362452;9504.47725375111;9518.714438566854;9317.34778565521;9476.289278304017;9603.471809050568;9740.80518389614;9759.609615615296;6880.311968803079;6908.067410899665;6996.860062798817;7012;7149.249328820399;7808.063032436144;7898.3681305496575;7901.209879012053;7909.683612655429;7919.84160316802;6034.668093254913;7113.928860711534;7158.821029474305;7219.494635375473;7287.562746235243;6968.860622787617;7007.649617519206;7040.225575186828;7113.715451381887;7136.036635054201;6605.306442823435;6683.264840867403;6688.097106890507;6693.899591506034;6702.698378573074;4767.523247675204;4776.95223047779;4811.903761924812;4854.441739200037;4891.26631005352;4767.475577686521;4776.617870570277;4839.637027222902;4900.926486102782;4913.9289489956545 08:41:02 INFO - PROCESS | 1665 | |4;prototype.html;300.998495007525;301.09670987038885;303.93009607790196;308.2386505331831;317.10892392377434;4594.448666160061;4598;4763.761811909404;4888.804447822087;5251.816186433475;4304.7383090740095;4905.136048301641;4905.533974272447;4974.960266290822;5134.486551344864;3312.96687033129;3966.8611598594057;3981.761094334342;3983.5418926823495;4005.7796821174825;3372.903806262962;3959.5644479107277;3971.424143499191;4009.97995010026;4034.9798251008706;3349.983250083746;3444.569428821397;3632.909177270563;3995.820188091543;4000.0999775050673;3646.9970758041486;3854.1906199698096;3914.628110329514;3940.2316548273097;3950.8617198398206;2618.56793629051;3399.8980030599123;3411.300683359905;3413.4367829308135;3432.9828350858334;4165.770882601456;4991.7753701083175;5011.774470148852;5025.14572522672;5034.395872495287;4184.497860256758;4475.955240447586;4495.483019452772;4500.167469018242;4537.525304276123;4781.497942716;5331.173668081454;5463.7814487420455;5468.343798744137;5477.616566840323;3539.3806083935465;3798.5631652360057;3804.0680033391995;3805.238952209569;3806.980965095157;5609.663420194802;5672.568096301719;5792.7972520961985;5837.630602882356;5884.234409827374;3808.257389808961;3809.657130858236;3818.0909545227387;3820.1595648957186;3839.3665045267776;3403.8937345362856;3444.50054742066;3871.245107204068;3875.205582855508;3908.4528166056775;4151.12826306479;4181.243877571427;4321.827126914951;4477.395551600493;4483.058557702853;4821.1679561766305;5489.945100548944;5515.917261241085;5530.695811730394;5543.722813859291;1095.4303762043692;1127.425013243252;1238.597455826842;1257.698152443407;1259.7102666386781;654.8821212181758;807.6083099696642;811.2739098506803;840.4033136473173;854.4104567848165;4377.723583736503;4426.84506042287;4504.301833215857;4831.516848315211;4884.975575122102;4533.252013417749;5336.491848831043;5351.652142610717;5361.437049109865;5390.434004429557;4417.367855983646;4589.518100599389;4697.436307643105;4854.902901942012;4856.2958371036;2492.4765799182014;2538.0862889359814;2637.31429828246;2791.1717824824773;2794.818336808101;5147.510986456281;5463.508284254436;5477.67133971963;5488.286522752017;5575.4424557544735;5012.473690262543;5099.515546023122;5394.06233449584;5473.539065661473;5483.314585676791;4862.95137048632;5213.516375916784;5476.698781566973;5507.917381239285;5537.529310008534;4300.4194433751045;4773.880652983703;4921.532454416824;4997.125503037027;5323.201519772065;4372.715773474714;4623.398958135421;5239.113918989085;5260.974939409778;5274.736263186902;4648.767561621973;4729.479757226771;4779.773652784287;5467.316585426822;5504.559635229252;3493.9301213976087;3600.981995090008;3606.350856845793;3628.8185590720886;3630.8970862924725;4424.955750442455;4779.657967595784;5177.559907407913;5232.3459567554055;5285.8635393390605;4545.636349092131;4821.601735496807;5093.23601459784;5137.9486205138965;5180.61145414103;4428.535003824681;4800.495947925418;4971.154903666313;5154.7938082476285;5161.703042146359;5003.974980125076;5011.774470148779;5037.370328708911;5431.701256430934;5442;4576.588107070246;4834.685745426536;5221.582273418041;5294.841154765363;5447.536959358499;5180.585553155664;5199.4020687621405;5396.541293989898;5411.970511058353;5419.485148910847 08:41:02 INFO - PROCESS | 1665 | |5;yui.html;679.874223268695;700.2507317170628;708.9468289878257;735.4226931858492;737.4432303610773;13955.651108722273;14470.131792092481;14474.855251447483;14493.202873841949;14516.419343226271;12718.491260349598;12774.872251277484;12799.872001279984;12830;12830.871691283084;631.4632562322013;650.3626446082842;651.5374084400081;654.3718030690543;794.6821271491392;653.2944420026383;657.7829316325601;764.4954330142107;789.4434423731255;791.663542994228;856.4475913036088;858.8368210040072;866.4844417571535;868.2359523619207;869.4870026684255;860.4192170285032;863.0894406401306;863.0937515608649;867.0072766682114;868.4962721621445;497.96737866393477;511.1897642237059;642.2870613618879;644.8065580325883;648.565461141032;12151.906328430483;12946.287954162517;12966.870331296755;13446.12600180995;13726.245056521888;12938.935305323414;13380.933095334462;13404.128731632412;13458.125221860646;13567;10230.335028223142;10369.22230832683;10378.792424151474;10437.686869393854;10505.94940505951;3501.142220156078;3523.154442933703;3576.695980841632;3725.5343082114737;3738.45792360109;80998.59500702459;83282.58358708168;83442.58278708628;84103;90759;9957;10166.593336266467;10190.643327483502;10231.693049208536;10466.267361284637;19934.800651993584;20213.898930505253;20225.393238202876;20642.896785515975;21013.78986210149;7521.05986751656;7531.397488200931;7577.318041376303;7613.314801667876;7735.883961740578;4085.5710150433774;4146.647534959502;4227.725197862129;4273.102648443862;4340.826366945287;2901.82589044658;3259.429599820022;3364.1757769346177;3365.973378119697;3378.6959173673995;570.6347937320119;632.34552238433;670.3229737964593;673.3367632881608;682.8975653651992;599.6402158704742;660.4023358860239;671.5634837355758;672.8334762066139;684.8116767888871;8561.22948934599;8591.484510929364;9028.50819255369;9108;9189.63241470347;575.4303239792575;610.9205803245638;662.2185820731582;678.8744082344782;681.7517452547348;4142.2543942090115;4436.46762388509;4458.308962110878;4463.330500424897;4536.8638940831825;113171.43414282876;114696;115329.4233528827;116275;117688.41155794167;97086.51456742671;101362.49318753359;101486.91078535312;102423;103744;87978.68031979525;89142;89747;91180.1763964717;94081.5295923516;8499.787505312417;8672.913270867462;8676.522791246542;8824.338174637056;8944.239739622206;46839.82900427517;52791;52791.47208528019;56198.7190064047;56279.43720562906;76348;76520;80956.19043809487;81618.43006484234;85171.5741421289;11244;11269.289988888195;11302;11549.538018479503;11774.705632359259;12049.51801927913;12071.551413830397;12255.816162757745;12298.569550065704;12339.9383003088;11700.2979821211;11763.470643820878;11771.470283837076;11828.112891532997;11850.52597896109;11544.319980822753;11849.072378374061;11868.347240901834;11991.100667449802;12482.375881206084;12663.050271229878;12675.619731408073;12758.553450629184;13340.933295333462;13700.65748356299;10856.185786065918;10882.347059176474;11058.502367393647;11092.889071109186;11236.767670763405;11038.834417483904;11320.9433952833;11435;11476.713082173012;11599.5360185595 08:41:02 INFO - PROCESS | 1665 | __end_tp_report 08:41:02 INFO - PROCESS | 1665 | __start_cc_report 08:41:02 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2271 08:41:02 INFO - PROCESS | 1665 | __end_cc_report 08:41:02 INFO - PROCESS | 1665 | __startTimestamp1482597662685__endTimestamp 08:41:02 INFO - PROCESS | 1665 | 08:41:02 INFO - PROCESS | 1665 | ------- Summary: start ------- 08:41:02 INFO - PROCESS | 1665 | Number of tests: 6 08:41:02 INFO - PROCESS | 1665 | 08:41:02 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5621.16 Median:5863.15 stddev:653.68 (11.1%) stddev-sans-first:618.43 08:41:02 INFO - PROCESS | 1665 | Values: 2848.3 3285.9 3287.5 3287.8 3352.5 5583.1 5607.9 5636.0 5640.1 5649.5 5574.8 5663.2 5675.3 5678.4 5736.4 5117.7 5123.5 5154.2 5185.5 5198.8 5119.8 5120.7 5148.8 5189.7 5268.1 5168.1 5183.3 5191.4 5206.6 5210.9 5182.4 5186.8 5197.9 5199.6 5215.5 5067.5 5178.2 5210.5 5244.2 5261.4 5525.4 5567.8 5595.4 5680.5 5700.4 6108.0 6109.8 6112.9 6120.6 6122.4 6225.8 6235.3 6237.7 6246.2 6278.9 5739.2 5746.9 5750.8 5759.9 5796.9 6141.1 6172.4 6234.5 6248.4 6251.5 6047.6 6059.5 6075.3 6075.5 6090.5 5584.9 6041.3 6191.9 6225.8 6226.9 5846.5 5918.6 6075.0 6085.5 6138.2 5231.7 5841.0 5944.1 6035.7 6035.7 4756.4 4762.6 4765.7 4777.6 4817.6 4162.1 4335.2 4398.5 4405.8 4414.9 5352.6 5403.5 5408.9 5417.5 5418.8 5867.8 5868.8 5877.7 5881.1 5882.0 5385.8 5388.8 5389.2 5394.4 5398.5 5052.9 5100.7 5114.4 5117.0 5119.7 6247.9 6258.2 6260.0 6260.2 6271.3 6116.3 6139.4 6142.4 6145.1 6155.1 6254.8 6255.6 6261.5 6268.3 6271.7 4494.8 6061.9 6064.4 6066.2 6093.7 6115.9 6121.8 6135.0 6162.3 6174.8 4729.2 5879.6 6021.8 6049.9 6060.3 5971.0 6011.8 6024.6 6026.2 6035.1 5863.1 5936.3 5963.9 5965.2 6005.9 6072.4 6075.6 6106.1 6119.6 6126.3 5835.6 5944.1 5949.7 5976.6 5988.4 08:41:02 INFO - PROCESS | 1665 | 08:41:02 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:81489.34 Median:37749.41 stddev:197944.97 (524.4%) stddev-sans-first:198406.88 08:41:02 INFO - PROCESS | 1665 | Values: 510.9 522.0 538.5 541.5 556.0 37993.8 39124.2 39223.8 39279.6 39335.4 39827.6 40380.6 40382.4 40427.4 40442.0 8274.4 9096.0 9235.2 9260.1 9275.6 8300.3 8489.4 8492.3 8497.8 10888.6 11007.9 11219.9 11225.5 11232.7 11251.5 9314.9 11221.2 11234.0 11234.6 11241.4 7068.7 8459.0 10838.4 11035.7 11095.0 38189.6 38518.4 38523.0 38593.0 38611.4 3243.6 3308.6 3324.9 3533.2 3824.9 27675.2 27680.7 27733.0 27959.6 28144.7 3049.8 3057.5 3255.5 3317.7 3322.1 439045.0 497656.0 497718.0 499218.0 499334.0 59726.4 60088.7 60110.7 60212.7 60236.7 62205.0 62314.8 62443.7 62495.0 62554.4 13164.5 15977.8 15997.3 16039.9 16044.8 12246.4 12318.9 12319.0 12326.0 12340.3 15486.5 15745.6 15852.5 15901.4 18145.5 6564.9 6970.3 7925.4 8097.5 8124.4 874.9 938.2 944.5 1010.6 1015.2 28226.6 28953.9 29210.3 29213.0 29923.4 1022.5 1090.8 1157.2 1157.3 1158.6 12890.6 13557.7 14669.9 15033.5 15346.6 683529.0 899915.0 902165.0 902464.0 902544.0 47172.8 47700.0 47937.8 52616.7 54592.0 1358.7 1380.0 1398.3 1400.9 1407.4 28715.9 28887.3 29244.0 29836.3 29952.4 47654.0 48069.0 48248.0 48357.8 48382.0 510820.0 838302.0 851771.7 852127.0 856944.0 41532.0 42069.0 42378.4 42557.9 42570.6 36400.0 45189.9 45474.6 45720.0 47406.8 31581.9 36007.3 36795.6 37595.0 37903.8 37279.8 37563.8 38055.8 38394.0 40518.2 36161.0 36623.6 37129.1 37313.8 37515.1 31570.8 33657.0 33802.3 33846.8 33950.5 23896.9 25201.7 25241.6 25744.7 25753.5 08:41:02 INFO - PROCESS | 1665 | 08:41:02 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:34425.96 Median:1994.08 stddev:102520.09 (5141.2%) stddev-sans-first:102775.76 08:41:02 INFO - PROCESS | 1665 | Values: 351.5 366.4 375.0 384.6 404.7 14504.3 14650.5 15402.6 19705.3 20208.6 13955.2 15458.9 18950.9 19528.2 19615.5 5550.1 5890.8 5893.1 5893.4 5893.4 5649.2 5767.9 5905.3 5975.4 5989.0 5882.3 5920.1 5921.6 5931.9 5943.5 5607.9 5696.6 5770.8 5774.7 5779.4 4927.5 5058.7 5066.2 5074.2 5074.8 16409.3 21336.8 21420.3 22300.8 24057.0 2224.4 2299.0 2390.7 2392.9 2563.0 14440.8 15292.9 15295.9 15322.2 15420.0 1398.4 1560.5 1646.5 1696.2 1830.8 113137.0 113389.4 115016.4 115504.0 122031.4 9035.9 11423.8 11500.0 11534.6 11718.9 14629.4 14642.0 14650.7 14654.7 14674.0 8275.0 8341.0 8379.5 8386.2 8488.6 6257.8 7553.9 7941.6 8089.4 8419.1 3636.6 3782.4 4484.3 4513.1 4601.6 1596.9 1854.0 1912.3 1917.7 1921.8 821.9 850.6 877.0 880.7 902.4 19041.3 20021.5 20044.7 20094.6 20097.2 902.6 915.6 918.0 979.6 985.7 10347.3 10853.8 10900.7 10920.2 10959.6 587659.0 611905.0 613800.0 637066.8 638339.0 97061.0 101942.0 104606.0 108660.0 136308.0 911.3 933.6 944.6 952.8 958.0 17914.3 18606.4 19007.0 19008.6 19132.2 40109.0 51309.0 52310.5 52971.0 54093.0 55062.0 73022.0 74609.3 76427.0 79943.6 7939.8 7979.6 8091.5 8180.6 8268.8 11584.4 12390.3 12544.4 12788.4 13003.9 9196.9 9712.0 10019.5 10661.0 10694.4 10527.3 10699.9 10704.0 10740.0 10768.5 10435.9 10749.5 10788.9 10866.6 11033.0 7879.7 7904.0 8192.5 8274.3 8276.0 9622.2 10546.9 10766.9 10953.0 11107.6 08:41:02 INFO - PROCESS | 1665 | 08:41:02 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5438.88 Median:5199.98 stddev:2994.02 (57.6%) stddev-sans-first:2986.56 08:41:02 INFO - PROCESS | 1665 | Values: 1338.5 1440.2 1581.9 1779.5 1825.9 7983.7 8463.5 8472.2 8475.8 8492.8 7390.4 7576.5 7595.2 7723.8 8263.5 2101.8 2133.1 2137.9 2228.4 2317.2 2349.1 2359.7 2363.3 2367.2 2369.1 1971.2 2282.7 2302.9 2336.3 2338.7 2302.1 2304.3 2328.4 2334.4 2336.5 2017.0 2341.3 2358.8 2362.5 2363.3 8246.2 8378.2 8381.9 8447.7 8490.1 4954.0 5034.6 5199.5 5210.2 5216.5 2840.9 3383.7 3433.2 3566.3 3580.1 945.4 1001.7 1057.5 1079.2 1091.5 10868.5 10966.2 10971.1 11003.6 11028.7 4463.2 4600.2 4658.7 4667.1 4732.7 9394.6 9400.5 9402.6 9403.1 9430.9 5200.5 5202.3 5204.1 5209.9 5210.6 4556.8 4730.9 4749.3 4775.5 4788.7 3579.8 3688.4 3690.7 3864.4 3958.6 1150.4 1153.0 1159.2 1224.6 1264.5 739.0 822.7 872.4 881.1 902.1 5484.0 5566.6 5652.9 5830.1 5972.9 910.9 924.2 950.3 1021.6 1022.2 3595.7 3667.9 3706.2 3717.9 3826.8 9882.7 10073.2 10096.4 10171.5 10317.0 9536.5 9991.3 10013.6 10018.4 10046.2 7577.2 7800.5 7811.5 7815.3 7820.7 5529.5 5547.8 5567.6 5723.5 5760.2 9473.1 9495.8 9498.2 9504.5 9518.7 9317.3 9476.3 9603.5 9740.8 9759.6 6880.3 6908.1 6996.9 7012.0 7149.2 7808.1 7898.4 7901.2 7909.7 7919.8 6034.7 7113.9 7158.8 7219.5 7287.6 6968.9 7007.6 7040.2 7113.7 7136.0 6605.3 6683.3 6688.1 6693.9 6702.7 4767.5 4777.0 4811.9 4854.4 4891.3 4767.5 4776.6 4839.6 4900.9 4913.9 08:41:02 INFO - PROCESS | 1665 | 08:41:02 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4273.24 Median:4768.82 stddev:1299.74 (27.3%) stddev-sans-first:1268.73 08:41:02 INFO - PROCESS | 1665 | Values: 301.0 301.1 303.9 308.2 317.1 4594.4 4598.0 4763.8 4888.8 5251.8 4304.7 4905.1 4905.5 4975.0 5134.5 3313.0 3966.9 3981.8 3983.5 4005.8 3372.9 3959.6 3971.4 4010.0 4035.0 3350.0 3444.6 3632.9 3995.8 4000.1 3647.0 3854.2 3914.6 3940.2 3950.9 2618.6 3399.9 3411.3 3413.4 3433.0 4165.8 4991.8 5011.8 5025.1 5034.4 4184.5 4476.0 4495.5 4500.2 4537.5 4781.5 5331.2 5463.8 5468.3 5477.6 3539.4 3798.6 3804.1 3805.2 3807.0 5609.7 5672.6 5792.8 5837.6 5884.2 3808.3 3809.7 3818.1 3820.2 3839.4 3403.9 3444.5 3871.2 3875.2 3908.5 4151.1 4181.2 4321.8 4477.4 4483.1 4821.2 5489.9 5515.9 5530.7 5543.7 1095.4 1127.4 1238.6 1257.7 1259.7 654.9 807.6 811.3 840.4 854.4 4377.7 4426.8 4504.3 4831.5 4885.0 4533.3 5336.5 5351.7 5361.4 5390.4 4417.4 4589.5 4697.4 4854.9 4856.3 2492.5 2538.1 2637.3 2791.2 2794.8 5147.5 5463.5 5477.7 5488.3 5575.4 5012.5 5099.5 5394.1 5473.5 5483.3 4863.0 5213.5 5476.7 5507.9 5537.5 4300.4 4773.9 4921.5 4997.1 5323.2 4372.7 4623.4 5239.1 5261.0 5274.7 4648.8 4729.5 4779.8 5467.3 5504.6 3493.9 3601.0 3606.4 3628.8 3630.9 4425.0 4779.7 5177.6 5232.3 5285.9 4545.6 4821.6 5093.2 5137.9 5180.6 4428.5 4800.5 4971.2 5154.8 5161.7 5004.0 5011.8 5037.4 5431.7 5442.0 4576.6 4834.7 5221.6 5294.8 5447.5 5180.6 5199.4 5396.5 5412.0 5419.5 08:41:02 INFO - PROCESS | 1665 | 08:41:02 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:20809.37 Median:4187.19 stddev:31348.45 (748.7%) stddev-sans-first:31399.96 08:41:02 INFO - PROCESS | 1665 | Values: 679.9 700.3 708.9 735.4 737.4 13955.7 14470.1 14474.9 14493.2 14516.4 12718.5 12774.9 12799.9 12830.0 12830.9 631.5 650.4 651.5 654.4 794.7 653.3 657.8 764.5 789.4 791.7 856.4 858.8 866.5 868.2 869.5 860.4 863.1 863.1 867.0 868.5 498.0 511.2 642.3 644.8 648.6 12151.9 12946.3 12966.9 13446.1 13726.2 12938.9 13380.9 13404.1 13458.1 13567.0 10230.3 10369.2 10378.8 10437.7 10505.9 3501.1 3523.2 3576.7 3725.5 3738.5 80998.6 83282.6 83442.6 84103.0 90759.0 9957.0 10166.6 10190.6 10231.7 10466.3 19934.8 20213.9 20225.4 20642.9 21013.8 7521.1 7531.4 7577.3 7613.3 7735.9 4085.6 4146.6 4227.7 4273.1 4340.8 2901.8 3259.4 3364.2 3366.0 3378.7 570.6 632.3 670.3 673.3 682.9 599.6 660.4 671.6 672.8 684.8 8561.2 8591.5 9028.5 9108.0 9189.6 575.4 610.9 662.2 678.9 681.8 4142.3 4436.5 4458.3 4463.3 4536.9 113171.4 114696.0 115329.4 116275.0 117688.4 97086.5 101362.5 101486.9 102423.0 103744.0 87978.7 89142.0 89747.0 91180.2 94081.5 8499.8 8672.9 8676.5 8824.3 8944.2 46839.8 52791.0 52791.5 56198.7 56279.4 76348.0 76520.0 80956.2 81618.4 85171.6 11244.0 11269.3 11302.0 11549.5 11774.7 12049.5 12071.6 12255.8 12298.6 12339.9 11700.3 11763.5 11771.5 11828.1 11850.5 11544.3 11849.1 11868.3 11991.1 12482.4 12663.1 12675.6 12758.6 13340.9 13700.7 10856.2 10882.3 11058.5 11092.9 11236.8 11038.8 11320.9 11435.0 11476.7 11599.5 08:41:02 INFO - PROCESS | 1665 | -------- Summary: end -------- 08:41:02 INFO - PROCESS | 1665 | 08:41:03 INFO - TEST-INFO | 1665: exit 0 08:41:03 INFO - TEST-OK | dromaeo_css | took 1120537ms 08:41:03 INFO - TEST-START | kraken 08:41:03 INFO - operating with platform_type : mac_ 08:41:03 INFO - Initialising browser for kraken test... 08:41:03 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpYrRKAm/profile http://localhost:49250/getInfo.html) 08:41:06 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 08:41:06 INFO - PROCESS | 1671 | colorDepth:24 08:41:06 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 08:41:06 INFO - PROCESS | 1671 | __metrics 08:41:06 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:41:06 INFO - PROCESS | 1671 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:41:07 INFO - TEST-INFO | 1671: exit 0 08:41:07 INFO - Browser initialized. 08:41:07 INFO - Running cycle 1/1 for kraken test... 08:41:07 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpYrRKAm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:41:19 INFO - PROCESS | 1672 | RSS: Main: 222588928 08:41:19 INFO - PROCESS | 1672 | 08:41:22 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection) 08:41:23 INFO - PROCESS | 1672 | RSS: Main: 273854464 08:41:23 INFO - PROCESS | 1672 | 08:41:25 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-dft) 08:41:26 INFO - PROCESS | 1672 | RSS: Main: 284962816 08:41:26 INFO - PROCESS | 1672 | 08:41:29 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-fft) 08:41:30 INFO - PROCESS | 1672 | RSS: Main: 381980672 08:41:30 INFO - PROCESS | 1672 | 08:41:32 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator) 08:41:32 INFO - PROCESS | 1672 | RSS: Main: 282415104 08:41:32 INFO - PROCESS | 1672 | 08:41:34 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:41:35 INFO - PROCESS | 1672 | RSS: Main: 283734016 08:41:35 INFO - PROCESS | 1672 | 08:41:38 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom) 08:41:38 INFO - PROCESS | 1672 | RSS: Main: 324829184 08:41:38 INFO - PROCESS | 1672 | 08:41:41 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate) 08:41:42 INFO - PROCESS | 1672 | RSS: Main: 326762496 08:41:42 INFO - PROCESS | 1672 | 08:41:45 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial) 08:41:45 INFO - PROCESS | 1672 | RSS: Main: 321376256 08:41:45 INFO - PROCESS | 1672 | 08:41:47 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:41:47 INFO - PROCESS | 1672 | RSS: Main: 299769856 08:41:47 INFO - PROCESS | 1672 | 08:41:49 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:41:50 INFO - PROCESS | 1672 | RSS: Main: 300154880 08:41:50 INFO - PROCESS | 1672 | 08:41:52 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:41:53 INFO - PROCESS | 1672 | RSS: Main: 303271936 08:41:53 INFO - PROCESS | 1672 | 08:41:56 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:41:57 INFO - PROCESS | 1672 | RSS: Main: 321757184 08:41:57 INFO - PROCESS | 1672 | 08:41:59 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:42:00 INFO - PROCESS | 1672 | RSS: Main: 314925056 08:42:00 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49250/tests/kraken/driver.html?testName=ai-astar) 08:42:02 INFO - PROCESS | 1672 | RSS: Main: 358993920 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | __start_tp_report 08:42:02 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 08:42:02 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 08:42:02 INFO - PROCESS | 1672 | |i|pagename|runs| 08:42:02 INFO - PROCESS | 1672 | |0;ai-astar;115;115;116;114;115;115;119;166;158;115 08:42:02 INFO - PROCESS | 1672 | |1;audio-beat-detection;147;108;110;143;110;111;110;110;142;109 08:42:02 INFO - PROCESS | 1672 | |2;audio-dft;182;179;186;184;180;182;182;182;187;183 08:42:02 INFO - PROCESS | 1672 | |3;audio-fft;80;59;58;88;61;58;58;64;83;58 08:42:02 INFO - PROCESS | 1672 | |4;audio-oscillator;82;69;94;71;67;69;91;68;68;92 08:42:02 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;92;84;86;84;86;83;87;83;86;84 08:42:02 INFO - PROCESS | 1672 | |6;imaging-darkroom;114;161;108;99;94;95;95;94;94;96 08:42:02 INFO - PROCESS | 1672 | |7;imaging-desaturate;81;85;82;79;85;84;87;80;81;83 08:42:02 INFO - PROCESS | 1672 | |8;json-parse-financial;56;56;56;54;58;60;59;58;57;58 08:42:02 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;64;75;76;74;71;70;69;71;69;96 08:42:02 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;91;100;81;86;83;90;99;100;80;85 08:42:02 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;137;137;139;139;140;142;143;136;203;160 08:42:02 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;166;166;162;167;166;167;163;163;165;165 08:42:02 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;65;67;68;67;65;67;67;66;64;64 08:42:02 INFO - PROCESS | 1672 | __end_tp_report 08:42:02 INFO - PROCESS | 1672 | __start_cc_report 08:42:02 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2098 08:42:02 INFO - PROCESS | 1672 | __end_cc_report 08:42:02 INFO - PROCESS | 1672 | __startTimestamp1482597722444__endTimestamp 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | ------- Summary: start ------- 08:42:02 INFO - PROCESS | 1672 | Number of tests: 14 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:124.80 Median:115.50 stddev:19.74 (17.1%) stddev-sans-first:20.62 08:42:02 INFO - PROCESS | 1672 | Values: 115.0 115.0 116.0 114.0 115.0 115.0 119.0 166.0 158.0 115.0 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:120.00 Median:110.50 stddev:16.63 (15.0%) stddev-sans-first:14.48 08:42:02 INFO - PROCESS | 1672 | Values: 147.0 108.0 110.0 143.0 110.0 111.0 110.0 110.0 142.0 109.0 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:182.70 Median:182.50 stddev:2.45 (1.3%) stddev-sans-first:2.59 08:42:02 INFO - PROCESS | 1672 | Values: 182.0 179.0 186.0 184.0 180.0 182.0 182.0 182.0 187.0 183.0 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:66.70 Median:62.50 stddev:12.01 (19.2%) stddev-sans-first:11.73 08:42:02 INFO - PROCESS | 1672 | Values: 80.0 59.0 58.0 88.0 61.0 58.0 58.0 64.0 83.0 58.0 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:76.50 stddev:11.36 (14.8%) stddev-sans-first:11.91 08:42:02 INFO - PROCESS | 1672 | Values: 82.0 69.0 94.0 71.0 67.0 69.0 91.0 68.0 68.0 92.0 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.00 stddev:2.68 (3.1%) stddev-sans-first:1.48 08:42:02 INFO - PROCESS | 1672 | Values: 92.0 84.0 86.0 84.0 86.0 83.0 87.0 83.0 86.0 84.0 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:105.00 Median:94.50 stddev:20.83 (22.0%) stddev-sans-first:21.84 08:42:02 INFO - PROCESS | 1672 | Values: 114.0 161.0 108.0 99.0 94.0 95.0 95.0 94.0 94.0 96.0 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:82.70 Median:83.50 stddev:2.54 (3.0%) stddev-sans-first:2.62 08:42:02 INFO - PROCESS | 1672 | Values: 81.0 85.0 82.0 79.0 85.0 84.0 87.0 80.0 81.0 83.0 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:58.00 stddev:1.75 (3.0%) stddev-sans-first:1.80 08:42:02 INFO - PROCESS | 1672 | Values: 56.0 56.0 56.0 54.0 58.0 60.0 59.0 58.0 57.0 58.0 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:73.50 Median:72.50 stddev:8.63 (11.9%) stddev-sans-first:8.44 08:42:02 INFO - PROCESS | 1672 | Values: 64.0 75.0 76.0 74.0 71.0 70.0 69.0 71.0 69.0 96.0 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:85.50 stddev:7.82 (9.1%) stddev-sans-first:8.28 08:42:02 INFO - PROCESS | 1672 | Values: 91.0 100.0 81.0 86.0 83.0 90.0 99.0 100.0 80.0 85.0 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:141.00 stddev:20.66 (14.7%) stddev-sans-first:21.55 08:42:02 INFO - PROCESS | 1672 | Values: 137.0 137.0 139.0 139.0 140.0 142.0 143.0 136.0 203.0 160.0 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:166.00 stddev:1.76 (1.1%) stddev-sans-first:1.83 08:42:02 INFO - PROCESS | 1672 | Values: 166.0 166.0 162.0 167.0 166.0 167.0 163.0 163.0 165.0 165.0 08:42:02 INFO - PROCESS | 1672 | 08:42:02 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:67.00 stddev:1.41 (2.1%) stddev-sans-first:1.45 08:42:02 INFO - PROCESS | 1672 | Values: 65.0 67.0 68.0 67.0 65.0 67.0 67.0 66.0 64.0 64.0 08:42:02 INFO - PROCESS | 1672 | -------- Summary: end -------- 08:42:02 INFO - PROCESS | 1672 | 08:42:03 INFO - TEST-INFO | 1672: exit 0 08:42:03 INFO - TEST-OK | kraken | took 60187ms 08:42:03 INFO - SUITE-END | took 1180s 08:42:03 INFO - Completed test suite (00:19:40) 08:42:03 INFO - javascript benchmark 08:42:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2848.3021659693372, 3285.884994025209, 3287.506873968906, 3287.8356082195883, 3352.5306457096012, 5583.05088135017, 5607.859803504914, 5635.957347890633, 5640.125780504023, 5649.519790817781, 5574.832755017356, 5663.150527420889, 5675.34733505647, 5678.375378708339, 5736.426357364261, 5117.692938423688, 5123.5388815006645, 5154.175331946889, 5185.533302002819, 5198.818041368553, 5119.846404607868, 5120.743962801864, 5148.7940482380845, 5189.662671926313, 5268.104422248208, 5168.147255702805, 5183.2743415921805, 5191.402988656297, 5206.557442617363, 5210.947890521084, 5182.37811462625, 5186.7925282988635, 5197.948020519784, 5199.610029247784, 5215.452377500384, 5067.49325067494, 5178.249153872667, 5210.47895210476, 5244.239585260116, 5261.368635763694, 5525.419830917776, 5567.832965011056, 5595.38450770415, 5680.460356266148, 5700.37295897451, 6108.0, 6109.799438327889, 6112.908306375408, 6120.571559990803, 6122.387761223887, 6225.782097626607, 6235.314115447342, 6237.656928868911, 6246.187995560548, 6278.87442251157, 5739.225204597409, 5746.942530574641, 5750.7987220447085, 5759.913601295984, 5796.94203057964, 6141.140240366352, 6172.351903050205, 6234.532410069263, 6248.4376406123665, 6251.531135164881, 6047.637141771508, 6059.515238780887, 6075.33171351151, 6075.453209211192, 6090.482309003695, 5584.888302234014, 6041.305249896231, 6191.876162476725, 6225.782097626561, 6226.875462490725, 5846.473817356458, 5918.615290006136, 6075.0, 6085.4523092921845, 6138.232720909887, 5231.686098834082, 5840.970795145997, 5944.108383742387, 6035.698215089228, 6035.7283922223605, 4756.381670382828, 4762.618990480753, 4765.7140571565815, 4777.617790576745, 4817.590504807131, 4162.105147393325, 4335.176316499918, 4398.494173170063, 4405.7797110144365, 4414.867553973386, 5352.57179425645, 5403.540699040547, 5408.945255675184, 5417.5395091416885, 5418.83743487696, 5867.765289388377, 5868.823935281948, 5877.706114694248, 5881.05903055509, 5881.9705901470215, 5385.784568617211, 5388.838334849961, 5389.245505629215, 5394.433584473652, 5398.514133727984, 5052.873678158002, 5100.6684565503865, 5114.43741188469, 5116.974415127975, 5119.718415487109, 6247.937520624827, 6258.249010118815, 6260.0, 6260.154879091265, 6271.3415091414745, 6116.296625887901, 6139.369480960463, 6142.40669208518, 6145.064304743992, 6155.107509411021, 6254.8123556293385, 6255.624662520263, 6261.530385221035, 6268.27914789805, 6271.686415679289, 4494.77526123699, 6061.909071363845, 6064.363241859718, 6066.211392518944, 6093.725782339894, 6115.879294474606, 6121.755129794758, 6135.0, 6162.291336496181, 6174.753009879554, 4729.243321068612, 5879.575087860515, 6021.849453763516, 6049.909251361145, 6060.303065147565, 5971.0, 6011.7595296187665, 6024.638521688713, 6026.246719160105, 6035.064564992434, 5863.149843272787, 5936.317323507852, 5963.880722385615, 5965.194698715618, 6005.879882402415, 6072.392760724069, 6075.635461872302, 6106.084087386927, 6119.632822030692, 6126.264848218242, 5835.649861008353, 5944.138099975566, 5949.702514874152, 5976.551758618034, 5988.371221021905], "value": 5578.626903151748, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.8978204359128, 521.9869503262419, 538.4830562659846, 541.515343767328, 555.9722013899307, 37993.81003094984, 39124.21751564967, 39223.80388098059, 39279.60720392795, 39335.40996885042, 39827.60172398275, 40380.59619403805, 40382.39426408606, 40427.39358909619, 40442.0, 8274.420790544635, 9095.954520227406, 9235.215006724437, 9260.073992600754, 9275.628974841033, 8300.252977232049, 8489.405741598091, 8492.320614350869, 8497.830043399128, 10888.564457421693, 11007.944960275187, 11219.887801122006, 11225.494852731606, 11232.663020109369, 11251.493682784294, 9314.906850931471, 11221.214514983954, 11234.0, 11234.550617975272, 11241.381724005176, 7068.717251309941, 8458.98492180936, 10838.40388778617, 11035.724106897393, 11095.0, 38189.618103819164, 38518.42222366667, 38523.0, 38593.03517412059, 38611.420828687595, 3243.6269828969735, 3308.6195087564756, 3324.8670053197843, 3533.1697051193205, 3824.9043773905596, 27675.169744907686, 27680.723192768015, 27733.0, 27959.58060629092, 28144.718552814415, 3049.786514943955, 3057.480228361184, 3255.4953982132806, 3317.717993970515, 3322.069820450278, 439045.0, 497656.0, 497718.0, 499218.0, 499334.0, 59726.402735972086, 60088.69955650194, 60110.699446503364, 60212.69893650504, 60236.69881650564, 62205.0, 62314.75370492565, 62443.68778156171, 62495.0, 62554.37445625485, 13164.473421063241, 15977.760333595006, 15997.28012239452, 16039.919800400923, 16044.75932861008, 12246.44890979908, 12318.876811231952, 12319.014478841671, 12326.0, 12340.321282329558, 15486.5354039379, 15745.606359841096, 15852.52442426729, 15901.443449479213, 18145.546361341072, 6564.901526477107, 6970.302969703091, 7925.365970722329, 8097.514149150952, 8124.431289809775, 874.9081346458657, 938.1697197979837, 944.4616568555857, 1010.5503051142172, 1015.2487159501916, 28226.576601350585, 28953.85523072371, 29210.269743256587, 29213.0, 29923.401531969237, 1022.483645758887, 1090.7654854206385, 1157.2073129906041, 1157.2940506291156, 1158.5539567266494, 12890.613281601567, 13557.661058473419, 14669.853301467063, 15033.473828416172, 15346.616334591501, 683529.0, 899915.0, 902165.0, 902464.0, 902544.0, 47172.7641361791, 47700.04599908052, 47937.760311198224, 52616.736916315174, 54592.0, 1358.7282543491342, 1379.9586012419443, 1398.3357904995046, 1400.9299535023413, 1407.444059596474, 28715.856420717762, 28887.277818054717, 29244.0, 29836.254093647833, 29952.400951981275, 47654.0, 48069.03861922812, 48248.03503929832, 48357.7910552239, 48382.0, 510820.0, 838302.0, 851771.7411412903, 852127.0, 856944.0, 41532.0, 42069.0, 42378.36432453454, 42557.93605159772, 42570.57429425666, 36400.0, 45189.870253243935, 45474.63576092722, 45720.0, 47406.76296618495, 31581.89463368863, 36007.27985440329, 36795.63204368029, 37595.0, 37903.81048094742, 37279.81360093182, 37563.81218093892, 38055.80972095122, 38394.0, 40518.1896362077, 36161.0, 36623.63376366202, 37129.071773205884, 37313.813430933755, 37515.06212344604, 31570.842145789124, 33657.0, 33802.3239535203, 33846.830765847, 33950.49074263938, 23896.88051559731, 25201.74798251994, 25241.621375679013, 25744.742552574233, 25753.484930300914], "value": 20171.728909061498, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.52368540627435, 366.36070057749225, 374.9737518373713, 384.57504457574373, 404.6863680647498, 14504.347304371302, 14650.487232946849, 15402.61493462664, 19705.310314138973, 20208.59582808343, 13955.162690238592, 15458.922705386485, 18950.905245473787, 19528.21887124513, 19615.509612259702, 5550.139728342114, 5890.764369425217, 5893.116032595102, 5893.44012318829, 5893.440123188312, 5649.23735295734, 5767.855803604902, 5905.291365036202, 5975.372585878486, 5989.0417533194695, 5882.294124705041, 5920.141579470974, 5921.61509501881, 5931.88136237275, 5943.524518038546, 5607.915881261784, 5696.575856035991, 5770.7691692332255, 5774.740136693859, 5779.393163717791, 4927.507249275044, 5058.747062646853, 5066.189409694432, 5074.238864170367, 5074.797008119671, 16409.343626254937, 21336.786632133637, 21420.25029123989, 22300.776992230032, 24057.0, 2224.3882932193615, 2299.011425087212, 2390.725066617344, 2392.868392238426, 2563.0, 14440.783388249185, 15292.929494935359, 15295.923520382437, 15322.15728134952, 15419.997700149566, 1398.3847107272868, 1560.5474412420385, 1646.538969088657, 1696.236693487923, 1830.7986121526621, 113137.0, 113389.43994585157, 115016.42491787487, 115504.0, 122031.3898430511, 9035.909640903506, 11423.771524569462, 11500.022498087756, 11534.59628912984, 11718.88281117178, 14629.414823407158, 14642.0, 14650.706985860435, 14654.706905861824, 14674.0, 8275.04836943747, 8340.958295208486, 8379.539125348043, 8386.203310685476, 8488.575571221538, 6257.812265632038, 7553.942448057276, 7941.6029198539845, 8089.393295502743, 8419.07390187079, 3636.636336366342, 3782.394816829294, 4484.282514797616, 4513.052259025641, 4601.608863246594, 1596.8562829345183, 1854.0173707934816, 1912.3402426162952, 1917.7027560728109, 1921.7982111878052, 821.8726097454904, 850.5704619167404, 876.9736907892774, 880.7445840706251, 902.4134312696668, 19041.333553325843, 20021.499462513555, 20044.69932951007, 20094.598108037757, 20097.19611215564, 902.6254104546671, 915.6154415145655, 918.0079228075093, 979.6277414582413, 985.7486340983004, 10347.327423717434, 10853.837192442119, 10900.672979810459, 10920.235583509078, 10959.616413425492, 587659.0, 611905.0, 613800.0, 637066.8146659237, 638339.0, 97061.0, 101942.0, 104606.0, 108660.0, 136308.0, 911.334725650279, 933.5565606337071, 944.6127087894068, 952.8094381123803, 957.9712608621752, 17914.283428662708, 18606.44180674582, 19007.0, 19008.619827603095, 19132.23471061142, 40109.0, 51309.0, 52310.47689523208, 52971.0, 54093.0, 55062.0, 73022.0, 74609.25390746241, 76427.0, 79943.60028199822, 7939.761807145795, 7979.5611241382285, 8091.543522166066, 8180.5909704515725, 8268.83462330762, 11584.362860042442, 12390.3185324808, 12544.372781361079, 12788.424520896397, 13003.869961300266, 9196.908030919605, 9711.980242074764, 10019.54912028975, 10661.040506354466, 10694.358338499715, 10527.263091583516, 10699.94650026745, 10704.036636702735, 10740.0, 10768.51541680642, 10435.895641043797, 10749.462526873469, 10788.892111079103, 10866.61966831157, 11033.0, 7879.684812607432, 7904.0, 8192.54940978252, 8274.255317021498, 8275.95862020686, 9622.230221582116, 10546.947265263625, 10766.892331076902, 10953.014228719454, 11107.555697771999], "value": 9232.567545006337, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.5448947357897, 1440.2222799688168, 1581.936722531099, 1779.5373202967226, 1825.8813177143484, 7983.680652773889, 8463.492190468569, 8472.195141461567, 8475.830483390344, 8492.830143397136, 7390.3718183954425, 7576.507527010741, 7595.240475952402, 7723.806904827382, 8263.504189748619, 2101.821345185661, 2133.072113630568, 2137.8824164670937, 2228.43174990378, 2317.188983855654, 2349.142562964514, 2359.6578496118054, 2363.2673871100014, 2367.1951536477595, 2369.087901158058, 1971.221367559813, 2282.7146606674164, 2302.896369663361, 2336.2874323331375, 2338.7193536775526, 2302.1366987379733, 2304.2626359564947, 2328.359701082216, 2334.4280651240383, 2336.4742932839977, 2016.9596608067902, 2341.30931375243, 2358.7759162879497, 2362.4566349739657, 2363.279199844047, 8246.216609422096, 8378.204070613281, 8381.916180838174, 8447.746567602982, 8490.10853860342, 4953.975230123862, 5034.647574669774, 5199.506046925536, 5210.244514545369, 5216.504432078947, 2840.9431811363866, 3383.712384447325, 3433.1760377509468, 3566.286742651454, 3580.0691820126694, 945.352433582991, 1001.697792869267, 1057.4924036462464, 1079.2445288298222, 1091.4597274349248, 10868.510917008754, 10966.177536684781, 10971.122310215163, 11003.559857605685, 11028.724281893017, 4463.196624607602, 4600.217962946307, 4658.697184682985, 4667.13657973276, 4732.739699316502, 9394.577244024036, 9400.529973501298, 9402.623895044122, 9403.106704863027, 9430.858537121812, 5200.453952335026, 5202.3497062867145, 5204.115300398941, 5209.947900521022, 5210.583153347723, 4556.840510582114, 4730.905381892343, 4749.287606858929, 4775.450823155382, 4788.664793464424, 3579.785212887183, 3688.4098544232793, 3690.704743620504, 3864.381698928158, 3958.6437220650278, 1150.3902931446346, 1152.9769404611861, 1159.2406973432414, 1224.5652793258264, 1264.5321231144535, 739.0134170881826, 822.658596682382, 872.3631748823268, 881.1012766977647, 902.0618556701089, 5484.040292948718, 5566.582506312043, 5652.943470565242, 5830.125481177773, 5972.880542389128, 910.9316801239801, 924.200566509964, 950.2967803825258, 1021.581151727788, 1022.1669339488293, 3595.7303202259936, 3667.94498082529, 3706.2031663192543, 3717.8698745544316, 3826.7703937763267, 9882.65410710636, 10073.194144468425, 10096.444695541815, 10171.54228059739, 10317.0, 9536.475493847765, 9991.300608957303, 10013.599456021677, 10018.448985305733, 10046.246531510165, 7577.242275772379, 7800.453968222283, 7811.531308121531, 7815.335696465864, 7820.687172513036, 5529.529989950738, 5547.805826796042, 5567.580267031881, 5723.456271654186, 5760.164776107525, 9473.052694730748, 9495.762605934908, 9498.19265362452, 9504.47725375111, 9518.714438566854, 9317.34778565521, 9476.289278304017, 9603.471809050568, 9740.80518389614, 9759.609615615296, 6880.311968803079, 6908.067410899665, 6996.860062798817, 7012.0, 7149.249328820399, 7808.063032436144, 7898.3681305496575, 7901.209879012053, 7909.683612655429, 7919.84160316802, 6034.668093254913, 7113.928860711534, 7158.821029474305, 7219.494635375473, 7287.562746235243, 6968.860622787617, 7007.649617519206, 7040.225575186828, 7113.715451381887, 7136.036635054201, 6605.306442823435, 6683.264840867403, 6688.097106890507, 6693.899591506034, 6702.698378573074, 4767.523247675204, 4776.95223047779, 4811.903761924812, 4854.441739200037, 4891.26631005352, 4767.475577686521, 4776.617870570277, 4839.637027222902, 4900.926486102782, 4913.9289489956545], "value": 4398.1809261342005, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.998495007525, 301.09670987038885, 303.93009607790196, 308.2386505331831, 317.10892392377434, 4594.448666160061, 4598.0, 4763.761811909404, 4888.804447822087, 5251.816186433475, 4304.7383090740095, 4905.136048301641, 4905.533974272447, 4974.960266290822, 5134.486551344864, 3312.96687033129, 3966.8611598594057, 3981.761094334342, 3983.5418926823495, 4005.7796821174825, 3372.903806262962, 3959.5644479107277, 3971.424143499191, 4009.97995010026, 4034.9798251008706, 3349.983250083746, 3444.569428821397, 3632.909177270563, 3995.820188091543, 4000.0999775050673, 3646.9970758041486, 3854.1906199698096, 3914.628110329514, 3940.2316548273097, 3950.8617198398206, 2618.56793629051, 3399.8980030599123, 3411.300683359905, 3413.4367829308135, 3432.9828350858334, 4165.770882601456, 4991.7753701083175, 5011.774470148852, 5025.14572522672, 5034.395872495287, 4184.497860256758, 4475.955240447586, 4495.483019452772, 4500.167469018242, 4537.525304276123, 4781.497942716, 5331.173668081454, 5463.7814487420455, 5468.343798744137, 5477.616566840323, 3539.3806083935465, 3798.5631652360057, 3804.0680033391995, 3805.238952209569, 3806.980965095157, 5609.663420194802, 5672.568096301719, 5792.7972520961985, 5837.630602882356, 5884.234409827374, 3808.257389808961, 3809.657130858236, 3818.0909545227387, 3820.1595648957186, 3839.3665045267776, 3403.8937345362856, 3444.50054742066, 3871.245107204068, 3875.205582855508, 3908.4528166056775, 4151.12826306479, 4181.243877571427, 4321.827126914951, 4477.395551600493, 4483.058557702853, 4821.1679561766305, 5489.945100548944, 5515.917261241085, 5530.695811730394, 5543.722813859291, 1095.4303762043692, 1127.425013243252, 1238.597455826842, 1257.698152443407, 1259.7102666386781, 654.8821212181758, 807.6083099696642, 811.2739098506803, 840.4033136473173, 854.4104567848165, 4377.723583736503, 4426.84506042287, 4504.301833215857, 4831.516848315211, 4884.975575122102, 4533.252013417749, 5336.491848831043, 5351.652142610717, 5361.437049109865, 5390.434004429557, 4417.367855983646, 4589.518100599389, 4697.436307643105, 4854.902901942012, 4856.2958371036, 2492.4765799182014, 2538.0862889359814, 2637.31429828246, 2791.1717824824773, 2794.818336808101, 5147.510986456281, 5463.508284254436, 5477.67133971963, 5488.286522752017, 5575.4424557544735, 5012.473690262543, 5099.515546023122, 5394.06233449584, 5473.539065661473, 5483.314585676791, 4862.95137048632, 5213.516375916784, 5476.698781566973, 5507.917381239285, 5537.529310008534, 4300.4194433751045, 4773.880652983703, 4921.532454416824, 4997.125503037027, 5323.201519772065, 4372.715773474714, 4623.398958135421, 5239.113918989085, 5260.974939409778, 5274.736263186902, 4648.767561621973, 4729.479757226771, 4779.773652784287, 5467.316585426822, 5504.559635229252, 3493.9301213976087, 3600.981995090008, 3606.350856845793, 3628.8185590720886, 3630.8970862924725, 4424.955750442455, 4779.657967595784, 5177.559907407913, 5232.3459567554055, 5285.8635393390605, 4545.636349092131, 4821.601735496807, 5093.23601459784, 5137.9486205138965, 5180.61145414103, 4428.535003824681, 4800.495947925418, 4971.154903666313, 5154.7938082476285, 5161.703042146359, 5003.974980125076, 5011.774470148779, 5037.370328708911, 5431.701256430934, 5442.0, 4576.588107070246, 4834.685745426536, 5221.582273418041, 5294.841154765363, 5447.536959358499, 5180.585553155664, 5199.4020687621405, 5396.541293989898, 5411.970511058353, 5419.485148910847], "value": 3857.0913253084873, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.874223268695, 700.2507317170628, 708.9468289878257, 735.4226931858492, 737.4432303610773, 13955.651108722273, 14470.131792092481, 14474.855251447483, 14493.202873841949, 14516.419343226271, 12718.491260349598, 12774.872251277484, 12799.872001279984, 12830.0, 12830.871691283084, 631.4632562322013, 650.3626446082842, 651.5374084400081, 654.3718030690543, 794.6821271491392, 653.2944420026383, 657.7829316325601, 764.4954330142107, 789.4434423731255, 791.663542994228, 856.4475913036088, 858.8368210040072, 866.4844417571535, 868.2359523619207, 869.4870026684255, 860.4192170285032, 863.0894406401306, 863.0937515608649, 867.0072766682114, 868.4962721621445, 497.96737866393477, 511.1897642237059, 642.2870613618879, 644.8065580325883, 648.565461141032, 12151.906328430483, 12946.287954162517, 12966.870331296755, 13446.12600180995, 13726.245056521888, 12938.935305323414, 13380.933095334462, 13404.128731632412, 13458.125221860646, 13567.0, 10230.335028223142, 10369.22230832683, 10378.792424151474, 10437.686869393854, 10505.94940505951, 3501.142220156078, 3523.154442933703, 3576.695980841632, 3725.5343082114737, 3738.45792360109, 80998.59500702459, 83282.58358708168, 83442.58278708628, 84103.0, 90759.0, 9957.0, 10166.593336266467, 10190.643327483502, 10231.693049208536, 10466.267361284637, 19934.800651993584, 20213.898930505253, 20225.393238202876, 20642.896785515975, 21013.78986210149, 7521.05986751656, 7531.397488200931, 7577.318041376303, 7613.314801667876, 7735.883961740578, 4085.5710150433774, 4146.647534959502, 4227.725197862129, 4273.102648443862, 4340.826366945287, 2901.82589044658, 3259.429599820022, 3364.1757769346177, 3365.973378119697, 3378.6959173673995, 570.6347937320119, 632.34552238433, 670.3229737964593, 673.3367632881608, 682.8975653651992, 599.6402158704742, 660.4023358860239, 671.5634837355758, 672.8334762066139, 684.8116767888871, 8561.22948934599, 8591.484510929364, 9028.50819255369, 9108.0, 9189.63241470347, 575.4303239792575, 610.9205803245638, 662.2185820731582, 678.8744082344782, 681.7517452547348, 4142.2543942090115, 4436.46762388509, 4458.308962110878, 4463.330500424897, 4536.8638940831825, 113171.43414282876, 114696.0, 115329.4233528827, 116275.0, 117688.41155794167, 97086.51456742671, 101362.49318753359, 101486.91078535312, 102423.0, 103744.0, 87978.68031979525, 89142.0, 89747.0, 91180.1763964717, 94081.5295923516, 8499.787505312417, 8672.913270867462, 8676.522791246542, 8824.338174637056, 8944.239739622206, 46839.82900427517, 52791.0, 52791.47208528019, 56198.7190064047, 56279.43720562906, 76348.0, 76520.0, 80956.19043809487, 81618.43006484234, 85171.5741421289, 11244.0, 11269.289988888195, 11302.0, 11549.538018479503, 11774.705632359259, 12049.51801927913, 12071.551413830397, 12255.816162757745, 12298.569550065704, 12339.9383003088, 11700.2979821211, 11763.470643820878, 11771.470283837076, 11828.112891532997, 11850.52597896109, 11544.319980822753, 11849.072378374061, 11868.347240901834, 11991.100667449802, 12482.375881206084, 12663.050271229878, 12675.619731408073, 12758.553450629184, 13340.933295333462, 13700.65748356299, 10856.185786065918, 10882.347059176474, 11058.502367393647, 11092.889071109186, 11236.767670763405, 11038.834417483904, 11320.9433952833, 11435.0, 11476.713082173012, 11599.5360185595], "value": 7109.277581627334, "unit": "score"}], "name": "dromaeo_css", "value": 7074.031387695288, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 116.0, 114.0, 115.0, 115.0, 119.0, 166.0, 158.0, 115.0], "value": 124.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 108.0, 110.0, 143.0, 110.0, 111.0, 110.0, 110.0, 142.0, 109.0], "value": 120.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 179.0, 186.0, 184.0, 180.0, 182.0, 182.0, 182.0, 187.0, 183.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 88.0, 61.0, 58.0, 58.0, 64.0, 83.0, 58.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 69.0, 94.0, 71.0, 67.0, 69.0, 91.0, 68.0, 68.0, 92.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 84.0, 86.0, 84.0, 86.0, 83.0, 87.0, 83.0, 86.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 161.0, 108.0, 99.0, 94.0, 95.0, 95.0, 94.0, 94.0, 96.0], "value": 105.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 82.0, 79.0, 85.0, 84.0, 87.0, 80.0, 81.0, 83.0], "value": 82.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 54.0, 58.0, 60.0, 59.0, 58.0, 57.0, 58.0], "value": 57.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 76.0, 74.0, 71.0, 70.0, 69.0, 71.0, 69.0, 96.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 100.0, 81.0, 86.0, 83.0, 90.0, 99.0, 100.0, 80.0, 85.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 139.0, 139.0, 140.0, 142.0, 143.0, 136.0, 203.0, 160.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 162.0, 167.0, 166.0, 167.0, 163.0, 163.0, 165.0, 165.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 68.0, 67.0, 65.0, 67.0, 67.0, 66.0, 64.0, 64.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1443.3, "alertThreshold": 2.0}]} 08:42:03 INFO - Return code: 0 08:42:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:42:03 INFO - # TBPL SUCCESS # 08:42:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:42:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:42:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:42:03 INFO - 2016-12-24 08:42:03.765 screenresolution[1676:10401] starting screenresolution argv=screenresolution get 08:42:03 INFO - 2016-12-24 08:42:03.768 screenresolution[1676:10401] Display 0: 1600x1200x32@60 08:42:03 INFO - 2016-12-24 08:42:03.775 screenresolution[1677:10404] starting screenresolution argv=screenresolution list 08:42:03 INFO - Available Modes on Display 0 08:42:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:42:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:42:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:42:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:42:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:42:04 INFO - Intel Iris: 08:42:04 INFO - Chipset Model: Intel Iris 08:42:04 INFO - Type: GPU 08:42:04 INFO - Bus: Built-In 08:42:04 INFO - VRAM (Dynamic, Max): 1536 MB 08:42:04 INFO - Vendor: Intel (0x8086) 08:42:04 INFO - Device ID: 0x0a2e 08:42:04 INFO - Revision ID: 0x0009 08:42:04 INFO - Displays: 08:42:04 INFO - Display: 08:42:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:42:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:42:04 INFO - Display Serial Number: 1600x1200 60 08:42:04 INFO - Main Display: Yes 08:42:04 INFO - Mirror: Off 08:42:04 INFO - Online: Yes 08:42:04 INFO - Rotation: Supported 08:42:04 INFO - Return code: 0 08:42:04 INFO - Running post-action listener: _resource_record_post_action 08:42:04 INFO - [mozharness: 2016-12-24 16:42:04.016334Z] Finished run-tests step (success) 08:42:04 INFO - Running post-run listener: _resource_record_post_run 08:42:04 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 33283072; Write bytes: 660532736; Read time: 372; Write time: 1910 08:42:04 INFO - TinderboxPrint: CPU usage
53.5% 08:42:04 INFO - TinderboxPrint: I/O read bytes / time
33,283,072 / 372 08:42:04 INFO - TinderboxPrint: I/O write bytes / time
660,532,736 / 1,910 08:42:04 INFO - TinderboxPrint: CPU idle
2,254.0 (46.5%) 08:42:04 INFO - TinderboxPrint: CPU system
967.7 (20.0%) 08:42:04 INFO - TinderboxPrint: CPU user
1,628.0 (33.6%) 08:42:04 INFO - TinderboxPrint: Swap in / out
377,643,008 / 0 08:42:04 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 245760; Write bytes: 352831488; Read time: 4; Write time: 679 08:42:04 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 29056000; Write bytes: 306361856; Read time: 341; Write time: 1226 08:42:04 INFO - Running post-run listener: _upload_blobber_files 08:42:04 INFO - Blob upload gear active. 08:42:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:42:04 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 08:42:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:42:04 INFO - (blobuploader) - INFO - Open directory for files ... 08:42:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:42:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:42:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:06 INFO - (blobuploader) - INFO - Done attempting. 08:42:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:42:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:42:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:06 INFO - (blobuploader) - INFO - Done attempting. 08:42:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:42:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:42:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:09 INFO - (blobuploader) - INFO - Done attempting. 08:42:09 INFO - (blobuploader) - INFO - Iteration through files over. 08:42:09 INFO - Return code: 0 08:42:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:42:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:42:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc43cc258a675b150a12f5545c86feb2ddc51257857b132d655cdbde2620fdf1951483aa2912d52940f1653a677057ac78dfd908eeb95711e996ec012462ea11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa8810c75451af4408b2bbc1aa840f2a810cf8b38478899222c3131900249c75baf1a0bd1863b6d6d925482a306bd5767e8d43d552dae5de9812711b8150047f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ef97ea61b20daee9fe516bccad2ecf65010ded5e63569cd18eb34ec6b9889489c8af3961b5717ea6967c4786b393a0fc05dcb4f0046f2e68d8b5645a4698724"} 08:42:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:42:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:42:09 INFO - Contents: 08:42:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc43cc258a675b150a12f5545c86feb2ddc51257857b132d655cdbde2620fdf1951483aa2912d52940f1653a677057ac78dfd908eeb95711e996ec012462ea11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa8810c75451af4408b2bbc1aa840f2a810cf8b38478899222c3131900249c75baf1a0bd1863b6d6d925482a306bd5767e8d43d552dae5de9812711b8150047f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ef97ea61b20daee9fe516bccad2ecf65010ded5e63569cd18eb34ec6b9889489c8af3961b5717ea6967c4786b393a0fc05dcb4f0046f2e68d8b5645a4698724"} 08:42:09 INFO - Running post-run listener: copy_logs_to_upload_dir 08:42:09 INFO - Copying logs to upload dir... 08:42:09 INFO - mkdir: /builds/slave/test/build/upload/logs 08:42:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.219601 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-24 08:42:09.369422) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-24 08:42:09.373106) ========= 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.t3V6e64WGx/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.i9BZkc4oNO/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc43cc258a675b150a12f5545c86feb2ddc51257857b132d655cdbde2620fdf1951483aa2912d52940f1653a677057ac78dfd908eeb95711e996ec012462ea11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa8810c75451af4408b2bbc1aa840f2a810cf8b38478899222c3131900249c75baf1a0bd1863b6d6d925482a306bd5767e8d43d552dae5de9812711b8150047f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ef97ea61b20daee9fe516bccad2ecf65010ded5e63569cd18eb34ec6b9889489c8af3961b5717ea6967c4786b393a0fc05dcb4f0046f2e68d8b5645a4698724"} build_url:https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011169 build_url: 'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc43cc258a675b150a12f5545c86feb2ddc51257857b132d655cdbde2620fdf1951483aa2912d52940f1653a677057ac78dfd908eeb95711e996ec012462ea11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa8810c75451af4408b2bbc1aa840f2a810cf8b38478899222c3131900249c75baf1a0bd1863b6d6d925482a306bd5767e8d43d552dae5de9812711b8150047f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ef97ea61b20daee9fe516bccad2ecf65010ded5e63569cd18eb34ec6b9889489c8af3961b5717ea6967c4786b393a0fc05dcb4f0046f2e68d8b5645a4698724"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-24 08:42:09.408046) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:42:09.408387) ========= 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.t3V6e64WGx/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.i9BZkc4oNO/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.005032 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:42:09.457520) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-24 08:42:09.457908) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-24 08:42:09.458257) ========= ========= Total master_lag: 0.22 =========