builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0163 starttime: 1484268267.61 results: success (0) buildid: 20170112144117 builduid: 812ccfb2206b4087ab8515ba0403bcf0 revision: 5be69f2ff0164ed5e53ac7fd70f88b220a1d6ade ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 16:44:27.611447) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 16:44:27.611908) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 16:44:27.612199) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 16:44:27.638885) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 16:44:27.639276) ========= 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.0NyIJxubzp/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.eTbyJTO8O7/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-12 16:44:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.9M=0.001s 2017-01-12 16:44:27 (19.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123322 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 16:44:27.798081) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 16:44:27.798374) ========= 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.0NyIJxubzp/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.eTbyJTO8O7/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.028648 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 16:44:27.851820) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-12 16:44:27.852132) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5be69f2ff0164ed5e53ac7fd70f88b220a1d6ade --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 5be69f2ff0164ed5e53ac7fd70f88b220a1d6ade --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0NyIJxubzp/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.eTbyJTO8O7/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-12 16:44:27,952 truncating revision to first 12 chars 2017-01-12 16:44:27,952 Setting DEBUG logging. 2017-01-12 16:44:27,952 attempt 1/10 2017-01-12 16:44:27,952 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5be69f2ff016?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-12 16:44:29,103 unpacking tar archive at: mozilla-aurora-5be69f2ff016/testing/mozharness/ program finished with exit code 0 elapsedTime=1.427618 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-12 16:44:29.305850) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 16:44:29.306147) ========= script_repo_revision: 5be69f2ff0164ed5e53ac7fd70f88b220a1d6ade ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 16:44:29.309527) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 16:44:29.309797) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 16:44:29.329754) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-12 16:44:29.330116) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.0NyIJxubzp/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.eTbyJTO8O7/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:44:29 INFO - MultiFileLogger online at 20170112 16:44:29 in /builds/slave/test 16:44:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 16:44:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:44:29 INFO - {'append_to_log': False, 16:44:29 INFO - 'base_work_dir': '/builds/slave/test', 16:44:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:44:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:44:29 INFO - 'branch': 'Mozilla-Aurora', 16:44:29 INFO - 'buildbot_json_path': 'buildprops.json', 16:44:29 INFO - 'config_files': ('talos/mac_config.py',), 16:44:29 INFO - 'default_actions': ('clobber', 16:44:29 INFO - 'read-buildbot-config', 16:44:29 INFO - 'download-and-extract', 16:44:29 INFO - 'populate-webroot', 16:44:29 INFO - 'create-virtualenv', 16:44:29 INFO - 'install', 16:44:29 INFO - 'run-tests'), 16:44:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:44:29 INFO - 'download_minidump_stackwalk': True, 16:44:29 INFO - 'download_symbols': 'ondemand', 16:44:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:44:29 INFO - 'tooltool.py': '/tools/tooltool.py', 16:44:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:44:29 INFO - '/tools/misc-python/virtualenv.py')}, 16:44:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:44:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:44:29 INFO - 'installer_path': 'installer.exe', 16:44:29 INFO - 'log_level': 'info', 16:44:29 INFO - 'log_name': 'talos', 16:44:29 INFO - 'log_to_console': True, 16:44:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:44:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:44:29 INFO - 'opt_config_files': (), 16:44:29 INFO - 'pip_index': False, 16:44:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:44:29 INFO - 'cmd': ('bash', 16:44:29 INFO - '-c', 16:44:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:44:29 INFO - 'enabled': True, 16:44:29 INFO - 'halt_on_failure': False, 16:44:29 INFO - 'name': 'check_screen_resolution'},), 16:44:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:44:29 INFO - 'cmd': ('bash', 16:44:29 INFO - '-c', 16:44:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:44:29 INFO - 'enabled': True, 16:44:29 INFO - 'halt_on_failure': False, 16:44:29 INFO - 'name': 'check_screen_resolution'},), 16:44:29 INFO - 'run_cmd_checks_enabled': True, 16:44:29 INFO - 'sps_profile': False, 16:44:29 INFO - 'sps_profile_interval': 0, 16:44:29 INFO - 'suite': 'dromaeojs-e10s', 16:44:29 INFO - 'system_bits': '32', 16:44:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:44:29 INFO - 'title': 't-yosemite-r7-0163', 16:44:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:44:29 INFO - 'use_talos_json': True, 16:44:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:44:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:44:29 INFO - 'work_dir': 'build'} 16:44:29 INFO - [mozharness: 2017-01-13 00:44:29.540447Z] Running clobber step. 16:44:29 INFO - Running pre-action listener: _resource_record_pre_action 16:44:29 INFO - Running main action method: clobber 16:44:29 INFO - rmtree: /builds/slave/test/build 16:44:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:44:30 INFO - Running post-action listener: _resource_record_post_action 16:44:30 INFO - [mozharness: 2017-01-13 00:44:30.959428Z] Finished clobber step (success) 16:44:30 INFO - [mozharness: 2017-01-13 00:44:30.959556Z] Running read-buildbot-config step. 16:44:30 INFO - Running pre-action listener: _resource_record_pre_action 16:44:30 INFO - Running main action method: read_buildbot_config 16:44:30 INFO - Using buildbot properties: 16:44:30 INFO - { 16:44:30 INFO - "project": "", 16:44:30 INFO - "product": "firefox", 16:44:30 INFO - "script_repo_revision": "production", 16:44:30 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 16:44:30 INFO - "repository": "", 16:44:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 16:44:30 INFO - "buildid": "20170112144117", 16:44:30 INFO - "pgo_build": "False", 16:44:30 INFO - "basedir": "/builds/slave/test", 16:44:30 INFO - "buildnumber": 30, 16:44:30 INFO - "slavename": "t-yosemite-r7-0163", 16:44:30 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 16:44:30 INFO - "platform": "yosemite_r7", 16:44:30 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 16:44:30 INFO - "branch": "mozilla-aurora", 16:44:30 INFO - "repo_path": "releases/mozilla-aurora", 16:44:30 INFO - "revision": "5be69f2ff0164ed5e53ac7fd70f88b220a1d6ade", 16:44:30 INFO - "stage_platform": "macosx64", 16:44:30 INFO - "builduid": "812ccfb2206b4087ab8515ba0403bcf0", 16:44:30 INFO - "slavebuilddir": "test" 16:44:30 INFO - } 16:44:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 16:44:30 INFO - Running post-action listener: _resource_record_post_action 16:44:30 INFO - [mozharness: 2017-01-13 00:44:30.963017Z] Finished read-buildbot-config step (success) 16:44:30 INFO - [mozharness: 2017-01-13 00:44:30.963127Z] Running download-and-extract step. 16:44:30 INFO - Running pre-action listener: _resource_record_pre_action 16:44:30 INFO - Running main action method: download_and_extract 16:44:30 INFO - mkdir: /builds/slave/test/build/tests 16:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:30 INFO - trying https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 16:44:30 INFO - Downloading https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/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 16:44:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/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 16:44:31 INFO - Downloaded 1199 bytes. 16:44:31 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 16:44:31 INFO - Using the following test package requirements: 16:44:31 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 16:44:31 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:44:31 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 16:44:31 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:44:31 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 16:44:31 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:44:31 INFO - u'jsshell-mac.zip'], 16:44:31 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:44:31 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 16:44:31 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 16:44:31 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:44:31 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 16:44:31 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:44:31 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 16:44:31 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:44:31 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 16:44:31 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:44:31 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 16:44:31 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 16:44:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 16:44:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:44:31 INFO - Fetch https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 16:44:32 INFO - Http code: 200 16:44:32 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 16:44:32 INFO - x-amz-cf-id: mAJz6evIQSQqbgRNcBHLIChoJamxiB-yZtfZonEg8Fe3srFR5xb5QA== 16:44:32 INFO - x-amz-version-id: Vf5EOX9snAxAKw2RIww4IlwnfjFaSQWj 16:44:32 INFO - Expected file size: 16275669 16:44:32 INFO - Obtained file size: 16275669 16:44:39 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 16:44:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 16:44:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:44:39 INFO - Fetch https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 16:44:40 INFO - Http code: 200 16:44:40 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 16:44:40 INFO - x-amz-cf-id: 7OA-NtjUSoU3dNtmnzn8fa_YOa_8zN8Lseno2w82KLEIdC-mJ8AnFQ== 16:44:40 INFO - x-amz-version-id: Vf5EOX9snAxAKw2RIww4IlwnfjFaSQWj 16:44:40 INFO - Expected file size: 16275669 16:44:40 INFO - Obtained file size: 16275669 16:44:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 16:44:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 16:44:46 INFO - Fetch https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 16:44:47 INFO - Http code: 200 16:44:47 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 16:44:47 INFO - x-amz-cf-id: 92iqp1sUncfROPa3K0Xv54UYPJ2v2veyuChlv9bRBrhZ8VC96fPlhg== 16:44:47 INFO - x-amz-version-id: cNdMXouPSrJgmDBO2XRCitBwi8xIepdB 16:44:47 INFO - Expected file size: 14409038 16:44:47 INFO - Obtained file size: 14409038 16:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:47 INFO - trying https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:44:47 INFO - Downloading https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 16:44:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/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 16:44:50 INFO - Downloaded 109996008 bytes. 16:44:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:44:50 INFO - mkdir: /builds/slave/test/properties 16:44:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:44:50 INFO - Writing to file /builds/slave/test/properties/build_url 16:44:50 INFO - Contents: 16:44:50 INFO - build_url:https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:44:51 INFO - Running post-action listener: _resource_record_post_action 16:44:51 INFO - Running post-action listener: set_extra_try_arguments 16:44:51 INFO - [mozharness: 2017-01-13 00:44:51.764832Z] Finished download-and-extract step (success) 16:44:51 INFO - [mozharness: 2017-01-13 00:44:51.764971Z] Running populate-webroot step. 16:44:51 INFO - Running pre-action listener: _resource_record_pre_action 16:44:51 INFO - Running main action method: populate_webroot 16:44:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:44:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:44:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:44:51 INFO - '--tppagecycles', 16:44:51 INFO - '7'], 16:44:51 INFO - 'tests': ['tsvgm']}}, 16:44:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:44:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:44:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:44:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:44:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:44:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:44:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:51 INFO - 'talos_options': ['--disable-e10s'], 16:44:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:51 INFO - 'talos_options': ['--disable-e10s'], 16:44:51 INFO - 'tests': ['damp', 'tps']}, 16:44:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:51 INFO - 'tests': ['damp', 'tps']}, 16:44:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:44:51 INFO - 'tests': ['dromaeo_dom']}, 16:44:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:44:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:44:51 INFO - 'tests': ['basic_compositor_video']}, 16:44:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:44:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:44:51 INFO - 'tests': ['a11yr', 16:44:51 INFO - 'ts_paint', 16:44:51 INFO - 'tpaint', 16:44:51 INFO - 'sessionrestore', 16:44:51 INFO - 'sessionrestore_no_auto_restore', 16:44:51 INFO - 'tabpaint']}, 16:44:51 INFO - 'other-e10s': {'tests': ['a11yr', 16:44:51 INFO - 'ts_paint', 16:44:51 INFO - 'tpaint', 16:44:51 INFO - 'sessionrestore', 16:44:51 INFO - 'sessionrestore_no_auto_restore', 16:44:51 INFO - 'tabpaint']}, 16:44:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:44:51 INFO - 'ts_paint', 16:44:51 INFO - 'tpaint', 16:44:51 INFO - 'sessionrestore', 16:44:51 INFO - 'sessionrestore_no_auto_restore', 16:44:51 INFO - 'tabpaint']}, 16:44:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:44:51 INFO - 'ts_paint', 16:44:51 INFO - 'tpaint', 16:44:51 INFO - 'sessionrestore', 16:44:51 INFO - 'sessionrestore_no_auto_restore', 16:44:51 INFO - 'tabpaint']}, 16:44:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:44:51 INFO - 'tests': ['a11yr', 16:44:51 INFO - 'ts_paint', 16:44:51 INFO - 'tpaint', 16:44:51 INFO - 'sessionrestore', 16:44:51 INFO - 'sessionrestore_no_auto_restore', 16:44:51 INFO - 'tabpaint']}, 16:44:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:44:51 INFO - 'tests': ['a11yr', 16:44:51 INFO - 'ts_paint', 16:44:51 INFO - 'tpaint', 16:44:51 INFO - 'sessionrestore', 16:44:51 INFO - 'sessionrestore_no_auto_restore', 16:44:51 INFO - 'tabpaint']}, 16:44:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:44:51 INFO - 'tests': ['tsvgx', 16:44:51 INFO - 'tsvgr_opacity', 16:44:51 INFO - 'tart', 16:44:51 INFO - 'tscrollx', 16:44:51 INFO - 'cart']}, 16:44:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:44:51 INFO - 'tsvgr_opacity', 16:44:51 INFO - 'tart', 16:44:51 INFO - 'tscrollx', 16:44:51 INFO - 'cart']}, 16:44:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:51 INFO - 'talos_options': ['--disable-e10s'], 16:44:51 INFO - 'tests': ['tp5o']}, 16:44:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:51 INFO - 'tests': ['tp5o']}, 16:44:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:51 INFO - 'talos_options': ['--disable-e10s', 16:44:51 INFO - '--xperf_path', 16:44:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:51 INFO - 'tests': ['tp5n']}, 16:44:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:51 INFO - 'talos_options': ['--xperf_path', 16:44:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:51 INFO - 'tests': ['tp5n']}}, 16:44:51 INFO - 'talos.zip': {'path': '', 16:44:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:44:51 INFO - Running post-action listener: _resource_record_post_action 16:44:51 INFO - [mozharness: 2017-01-13 00:44:51.783503Z] Finished populate-webroot step (success) 16:44:51 INFO - [mozharness: 2017-01-13 00:44:51.783603Z] Running create-virtualenv step. 16:44:51 INFO - Running pre-action listener: _resource_record_pre_action 16:44:51 INFO - Running main action method: create_virtualenv 16:44:51 INFO - Creating virtualenv /builds/slave/test/build/venv 16:44:51 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 16:44:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:44:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:44:51 INFO - Using real prefix '/tools/python27' 16:44:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:44:52 INFO - Installing distribute.............................................................................................................................................................................................done. 16:44:55 INFO - Installing pip.................done. 16:44:55 INFO - Return code: 0 16:44:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:44:55 INFO - Reading from file tmpfile_stdout 16:44:55 INFO - Output received: 16:44:55 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) 16:44:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:55 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 0x10cc57490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff54256c250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6bd30>, '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 0x10c8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63b20>, '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.0NyIJxubzp/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.eTbyJTO8O7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:44:55 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 16:44:55 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 16:44:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0NyIJxubzp/Render', 16:44:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:55 INFO - 'HOME': '/Users/cltbld', 16:44:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:55 INFO - 'LOGNAME': 'cltbld', 16:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:55 INFO - 'MOZ_NO_REMOTE': '1', 16:44:55 INFO - 'NO_EM_RESTART': '1', 16:44:55 INFO - 'PAGER': '/bin/cat', 16:44:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:55 INFO - 'PWD': '/builds/slave/test', 16:44:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:55 INFO - 'SHELL': '/bin/bash', 16:44:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTbyJTO8O7/Listeners', 16:44:55 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:44:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:55 INFO - 'USER': 'cltbld', 16:44:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:55 INFO - 'XPC_FLAGS': '0x0', 16:44:55 INFO - 'XPC_SERVICE_NAME': '0', 16:44:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:56 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 16:44:56 INFO - Cleaning up... 16:44:56 INFO - Return code: 0 16:44:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:44:56 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')]} 16:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:56 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')]} 16:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:56 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 0x10cc57490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff54256c250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6bd30>, '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 0x10c8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63b20>, '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.0NyIJxubzp/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.eTbyJTO8O7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:44:56 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 16:44:56 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 16:44:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0NyIJxubzp/Render', 16:44:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:56 INFO - 'HOME': '/Users/cltbld', 16:44:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:56 INFO - 'LOGNAME': 'cltbld', 16:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:56 INFO - 'MOZ_NO_REMOTE': '1', 16:44:56 INFO - 'NO_EM_RESTART': '1', 16:44:56 INFO - 'PAGER': '/bin/cat', 16:44:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:56 INFO - 'PWD': '/builds/slave/test', 16:44:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:56 INFO - 'SHELL': '/bin/bash', 16:44:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTbyJTO8O7/Listeners', 16:44:56 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:44:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:56 INFO - 'USER': 'cltbld', 16:44:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:56 INFO - 'XPC_FLAGS': '0x0', 16:44:56 INFO - 'XPC_SERVICE_NAME': '0', 16:44:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:56 INFO - Downloading/unpacking psutil>=3.1.1 16:44:56 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 16:44:56 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 16:44:56 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 16:44:56 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 16:44:56 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 16:44:56 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 16:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:44:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:44:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:44:59 INFO - Installing collected packages: psutil 16:44:59 INFO - Running setup.py install for psutil 16:44:59 INFO - building 'psutil._psutil_osx' extension 16:44:59 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 16:45:00 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 16:45:00 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 16:45:00 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 16:45:00 INFO - building 'psutil._psutil_posix' extension 16:45:00 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 16:45:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:45:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:45:00 INFO - ^ 16:45:00 INFO - 1 warning generated. 16:45:00 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 16:45:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:45:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:45:00 INFO - Successfully installed psutil 16:45:00 INFO - Cleaning up... 16:45:01 INFO - Return code: 0 16:45:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:01 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 0x10cc57490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff54256c250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6bd30>, '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 0x10c8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63b20>, '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.0NyIJxubzp/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.eTbyJTO8O7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:45:01 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 16:45:01 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 16:45:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0NyIJxubzp/Render', 16:45:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:01 INFO - 'HOME': '/Users/cltbld', 16:45:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:01 INFO - 'LOGNAME': 'cltbld', 16:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:01 INFO - 'MOZ_NO_REMOTE': '1', 16:45:01 INFO - 'NO_EM_RESTART': '1', 16:45:01 INFO - 'PAGER': '/bin/cat', 16:45:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:01 INFO - 'PWD': '/builds/slave/test', 16:45:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:01 INFO - 'SHELL': '/bin/bash', 16:45:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTbyJTO8O7/Listeners', 16:45:01 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:45:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:01 INFO - 'USER': 'cltbld', 16:45:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:01 INFO - 'XPC_FLAGS': '0x0', 16:45:01 INFO - 'XPC_SERVICE_NAME': '0', 16:45:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:45:01 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 16:45:01 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 16:45:01 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 16:45:01 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 16:45:01 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 16:45:01 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 16:45:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:45:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:45:04 INFO - Installing collected packages: mozsystemmonitor 16:45:04 INFO - Running setup.py install for mozsystemmonitor 16:45:04 INFO - Successfully installed mozsystemmonitor 16:45:04 INFO - Cleaning up... 16:45:04 INFO - Return code: 0 16:45:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:04 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 0x10cc57490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff54256c250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6bd30>, '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 0x10c8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63b20>, '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.0NyIJxubzp/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.eTbyJTO8O7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:45:04 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 16:45:04 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 16:45:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0NyIJxubzp/Render', 16:45:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:04 INFO - 'HOME': '/Users/cltbld', 16:45:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:04 INFO - 'LOGNAME': 'cltbld', 16:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:04 INFO - 'MOZ_NO_REMOTE': '1', 16:45:04 INFO - 'NO_EM_RESTART': '1', 16:45:04 INFO - 'PAGER': '/bin/cat', 16:45:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:04 INFO - 'PWD': '/builds/slave/test', 16:45:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:04 INFO - 'SHELL': '/bin/bash', 16:45:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTbyJTO8O7/Listeners', 16:45:04 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:45:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:04 INFO - 'USER': 'cltbld', 16:45:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:04 INFO - 'XPC_FLAGS': '0x0', 16:45:04 INFO - 'XPC_SERVICE_NAME': '0', 16:45:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:04 INFO - Downloading/unpacking jsonschema==2.5.1 16:45:04 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 16:45:04 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 16:45:04 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 16:45:04 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 16:45:04 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 16:45:04 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 16:45:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:45:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:07 INFO - Downloading functools32-3.2.3-2.tar.gz 16:45:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:45:07 INFO - warning: no files found matching '*.txt' 16:45:07 INFO - no previously-included directories found matching 'build' 16:45:07 INFO - no previously-included directories found matching 'dist' 16:45:07 INFO - no previously-included directories found matching '.git*' 16:45:07 INFO - Installing collected packages: jsonschema, functools32 16:45:07 INFO - Running setup.py install for functools32 16:45:07 INFO - warning: no files found matching '*.txt' 16:45:07 INFO - no previously-included directories found matching 'build' 16:45:07 INFO - no previously-included directories found matching 'dist' 16:45:07 INFO - no previously-included directories found matching '.git*' 16:45:07 INFO - Successfully installed jsonschema functools32 16:45:07 INFO - Cleaning up... 16:45:07 INFO - Return code: 0 16:45:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:07 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 0x10cc57490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff54256c250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6bd30>, '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 0x10c8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63b20>, '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.0NyIJxubzp/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.eTbyJTO8O7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:45:07 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 16:45:07 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 16:45:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0NyIJxubzp/Render', 16:45:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:07 INFO - 'HOME': '/Users/cltbld', 16:45:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:07 INFO - 'LOGNAME': 'cltbld', 16:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:07 INFO - 'MOZ_NO_REMOTE': '1', 16:45:07 INFO - 'NO_EM_RESTART': '1', 16:45:07 INFO - 'PAGER': '/bin/cat', 16:45:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:07 INFO - 'PWD': '/builds/slave/test', 16:45:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:07 INFO - 'SHELL': '/bin/bash', 16:45:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTbyJTO8O7/Listeners', 16:45:07 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:45:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:07 INFO - 'USER': 'cltbld', 16:45:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:07 INFO - 'XPC_FLAGS': '0x0', 16:45:07 INFO - 'XPC_SERVICE_NAME': '0', 16:45:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:45:08 INFO - Cleaning up... 16:45:08 INFO - Return code: 0 16:45:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:45:08 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')]} 16:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:08 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')]} 16:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:08 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 0x10cc57490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff54256c250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6bd30>, '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 0x10c8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63b20>, '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.0NyIJxubzp/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.eTbyJTO8O7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:45:08 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 16:45:08 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 16:45:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0NyIJxubzp/Render', 16:45:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:08 INFO - 'HOME': '/Users/cltbld', 16:45:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:08 INFO - 'LOGNAME': 'cltbld', 16:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:08 INFO - 'MOZ_NO_REMOTE': '1', 16:45:08 INFO - 'NO_EM_RESTART': '1', 16:45:08 INFO - 'PAGER': '/bin/cat', 16:45:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:08 INFO - 'PWD': '/builds/slave/test', 16:45:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:08 INFO - 'SHELL': '/bin/bash', 16:45:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTbyJTO8O7/Listeners', 16:45:08 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:45:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:08 INFO - 'USER': 'cltbld', 16:45:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:08 INFO - 'XPC_FLAGS': '0x0', 16:45:08 INFO - 'XPC_SERVICE_NAME': '0', 16:45:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:08 INFO - Downloading/unpacking blobuploader==1.2.4 16:45:08 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 16:45:08 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 16:45:08 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 16:45:08 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 16:45:08 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 16:45:08 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 16:45:10 INFO - Downloading blobuploader-1.2.4.tar.gz 16:45:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:45:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:45:11 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 16:45:11 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 16:45:11 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 16:45:11 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 16:45:11 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 16:45:11 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 16:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:45:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:45:11 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 16:45:11 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 16:45:11 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 16:45:11 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 16:45:11 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 16:45:11 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 16:45:12 INFO - Downloading docopt-0.6.1.tar.gz 16:45:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:45:12 INFO - Installing collected packages: blobuploader, requests, docopt 16:45:12 INFO - Running setup.py install for blobuploader 16:45:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:45:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:45:12 INFO - Running setup.py install for requests 16:45:12 INFO - Running setup.py install for docopt 16:45:13 INFO - Successfully installed blobuploader requests docopt 16:45:13 INFO - Cleaning up... 16:45:13 INFO - Return code: 0 16:45:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:13 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')]} 16:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:13 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')]} 16:45:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:13 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 0x10cc57490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff54256c250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6bd30>, '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 0x10c8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63b20>, '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.0NyIJxubzp/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.eTbyJTO8O7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:45:13 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 16:45:13 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 16:45:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0NyIJxubzp/Render', 16:45:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:13 INFO - 'HOME': '/Users/cltbld', 16:45:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:13 INFO - 'LOGNAME': 'cltbld', 16:45:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:13 INFO - 'MOZ_NO_REMOTE': '1', 16:45:13 INFO - 'NO_EM_RESTART': '1', 16:45:13 INFO - 'PAGER': '/bin/cat', 16:45:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:13 INFO - 'PWD': '/builds/slave/test', 16:45:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:13 INFO - 'SHELL': '/bin/bash', 16:45:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTbyJTO8O7/Listeners', 16:45:13 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:45:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:13 INFO - 'USER': 'cltbld', 16:45:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:13 INFO - 'XPC_FLAGS': '0x0', 16:45:13 INFO - 'XPC_SERVICE_NAME': '0', 16:45:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:45:13 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-rXySDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:45:13 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-2_mk1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:45:13 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-DGN1J6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:45:13 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-wzL7Es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:45:13 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-kqzgov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:45:14 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-ELo1TE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:45:14 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-fnJnaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:45:14 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-VhgoKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:45:14 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-DSDQFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:45:14 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-PevAn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:45:14 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-LcCk9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:45:14 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-gXB3E8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:45:14 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-zJ8p1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:45:14 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-6pcXsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:14 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-H0snJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:45:15 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-rC0NTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:45:15 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-AiQ7pn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:45:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:45:15 INFO - Running setup.py install for manifestparser 16:45:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:45:15 INFO - Running setup.py install for mozcrash 16:45:15 INFO - Running setup.py install for mozdebug 16:45:15 INFO - Running setup.py install for mozdevice 16:45:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:45:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:45:15 INFO - Running setup.py install for mozfile 16:45:16 INFO - Running setup.py install for mozhttpd 16:45:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:45:16 INFO - Running setup.py install for mozinfo 16:45:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:45:16 INFO - Running setup.py install for mozInstall 16:45:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:45:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:45:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:45:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:45:16 INFO - Running setup.py install for mozleak 16:45:16 INFO - Running setup.py install for mozlog 16:45:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:45:16 INFO - Running setup.py install for moznetwork 16:45:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:45:16 INFO - Running setup.py install for mozprocess 16:45:17 INFO - Running setup.py install for mozprofile 16:45:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:45:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:45:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:45:17 INFO - Running setup.py install for mozrunner 16:45:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:45:17 INFO - Running setup.py install for mozscreenshot 16:45:17 INFO - Running setup.py install for moztest 16:45:17 INFO - Running setup.py install for mozversion 16:45:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:45:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:45:17 INFO - Cleaning up... 16:45:17 INFO - Return code: 0 16:45:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:17 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 0x10cc57490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff54256c250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6bd30>, '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 0x10c8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63b20>, '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.0NyIJxubzp/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.eTbyJTO8O7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:45:17 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 16:45:17 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 16:45:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0NyIJxubzp/Render', 16:45:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:17 INFO - 'HOME': '/Users/cltbld', 16:45:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:17 INFO - 'LOGNAME': 'cltbld', 16:45:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:17 INFO - 'MOZ_NO_REMOTE': '1', 16:45:17 INFO - 'NO_EM_RESTART': '1', 16:45:17 INFO - 'PAGER': '/bin/cat', 16:45:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:17 INFO - 'PWD': '/builds/slave/test', 16:45:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:17 INFO - 'SHELL': '/bin/bash', 16:45:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTbyJTO8O7/Listeners', 16:45:17 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:45:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:17 INFO - 'USER': 'cltbld', 16:45:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:17 INFO - 'XPC_FLAGS': '0x0', 16:45:17 INFO - 'XPC_SERVICE_NAME': '0', 16:45:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:45:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-9jTfmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:45:18 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)) 16:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:45:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-098TtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:45:18 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)) 16:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:45:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-ku0_ki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:45:18 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)) 16:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:45:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-x3FBBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:45:18 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)) 16:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:45:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-lsBxzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:45:18 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)) 16:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:45:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-jcghzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:45:18 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)) 16:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:45:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-aifdpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:45:18 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)) 16:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:45:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-RYpj5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:45:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-SE0iuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:45:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-mBWEFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:45:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-X9vPtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:45:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-V0pjlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:45:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-_dzN1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:45:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-wA8KdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-7H5utK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:45:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-sGiTbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:45:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-nD3QGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:45:19 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)) 16:45:19 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)) 16:45:19 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)) 16:45:19 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)) 16:45:19 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)) 16:45:19 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)) 16:45:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:22 INFO - Downloading blessings-1.6.tar.gz 16:45:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:45:22 INFO - Installing collected packages: blessings 16:45:22 INFO - Running setup.py install for blessings 16:45:22 INFO - Successfully installed blessings 16:45:22 INFO - Cleaning up... 16:45:22 INFO - Return code: 0 16:45:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:45:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:45:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:23 INFO - Reading from file tmpfile_stdout 16:45:23 INFO - Current package versions: 16:45:23 INFO - blessings == 1.6 16:45:23 INFO - blobuploader == 1.2.4 16:45:23 INFO - docopt == 0.6.1 16:45:23 INFO - functools32 == 3.2.3-2 16:45:23 INFO - jsonschema == 2.5.1 16:45:23 INFO - manifestparser == 1.1 16:45:23 INFO - mozInstall == 1.12 16:45:23 INFO - mozcrash == 1.0 16:45:23 INFO - mozdebug == 0.1 16:45:23 INFO - mozdevice == 0.48 16:45:23 INFO - mozfile == 1.2 16:45:23 INFO - mozhttpd == 0.7 16:45:23 INFO - mozinfo == 0.9 16:45:23 INFO - mozleak == 0.1 16:45:23 INFO - mozlog == 3.4 16:45:23 INFO - moznetwork == 0.27 16:45:23 INFO - mozprocess == 0.23 16:45:23 INFO - mozprofile == 0.28 16:45:23 INFO - mozrunner == 6.13 16:45:23 INFO - mozscreenshot == 0.1 16:45:23 INFO - mozsystemmonitor == 0.3 16:45:23 INFO - moztest == 0.8 16:45:23 INFO - mozversion == 1.4 16:45:23 INFO - psutil == 3.1.1 16:45:23 INFO - requests == 1.2.3 16:45:23 INFO - wsgiref == 0.1.2 16:45:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45: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')]} 16:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45: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')]} 16:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:23 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 0x10cc57490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff54256c250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6bd30>, '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 0x10c8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63b20>, '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.0NyIJxubzp/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.eTbyJTO8O7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:45:23 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 16:45:23 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 16:45:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0NyIJxubzp/Render', 16:45:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:23 INFO - 'HOME': '/Users/cltbld', 16:45:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:23 INFO - 'LOGNAME': 'cltbld', 16:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:23 INFO - 'MOZ_NO_REMOTE': '1', 16:45:23 INFO - 'NO_EM_RESTART': '1', 16:45:23 INFO - 'PAGER': '/bin/cat', 16:45:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:23 INFO - 'PWD': '/builds/slave/test', 16:45:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:23 INFO - 'SHELL': '/bin/bash', 16:45:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTbyJTO8O7/Listeners', 16:45:23 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:45:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:23 INFO - 'USER': 'cltbld', 16:45:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:23 INFO - 'XPC_FLAGS': '0x0', 16:45:23 INFO - 'XPC_SERVICE_NAME': '0', 16:45:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 INFO - Cleaning up... 16:45:24 INFO - Return code: 0 16:45:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:45:24 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')]} 16:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:24 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')]} 16:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cc57490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff54256c250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6bd30>, '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 0x10c8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63b20>, '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.0NyIJxubzp/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.eTbyJTO8O7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:45:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:45:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0NyIJxubzp/Render', 16:45:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:24 INFO - 'HOME': '/Users/cltbld', 16:45:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:24 INFO - 'LOGNAME': 'cltbld', 16:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:24 INFO - 'MOZ_NO_REMOTE': '1', 16:45:24 INFO - 'NO_EM_RESTART': '1', 16:45:24 INFO - 'PAGER': '/bin/cat', 16:45:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:24 INFO - 'PWD': '/builds/slave/test', 16:45:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:24 INFO - 'SHELL': '/bin/bash', 16:45:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTbyJTO8O7/Listeners', 16:45:24 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:45:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:24 INFO - 'USER': 'cltbld', 16:45:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:24 INFO - 'XPC_FLAGS': '0x0', 16:45:24 INFO - 'XPC_SERVICE_NAME': '0', 16:45:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:45:24 INFO - Cleaning up... 16:45:24 INFO - Return code: 0 16:45:24 INFO - Running post-action listener: _resource_record_post_action 16:45:24 INFO - Running post-action listener: _start_resource_monitoring 16:45:24 INFO - Starting resource monitoring. 16:45:24 INFO - [mozharness: 2017-01-13 00:45:24.879086Z] Finished create-virtualenv step (success) 16:45:24 INFO - [mozharness: 2017-01-13 00:45:24.880142Z] Running install step. 16:45:24 INFO - Running pre-action listener: _resource_record_pre_action 16:45:24 INFO - Running main action method: install 16:45:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:25 INFO - Reading from file tmpfile_stdout 16:45:25 INFO - Detecting whether we're running mozinstall >=1.0... 16:45:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:45:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:45:25 INFO - Reading from file tmpfile_stdout 16:45:25 INFO - Output received: 16:45:25 INFO - Usage: mozinstall [options] installer 16:45:25 INFO - Options: 16:45:25 INFO - -h, --help show this help message and exit 16:45:25 INFO - -d DEST, --destination=DEST 16:45:25 INFO - Directory to install application into. [default: 16:45:25 INFO - "/builds/slave/test"] 16:45:25 INFO - --app=APP Application being installed. [default: firefox] 16:45:25 INFO - mkdir: /builds/slave/test/build/application 16:45:25 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'] 16:45:25 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 16:45:56 INFO - Reading from file tmpfile_stdout 16:45:56 INFO - Output received: 16:45:56 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 16:45:56 INFO - Running post-action listener: _resource_record_post_action 16:45:56 INFO - [mozharness: 2017-01-13 00:45:56.613573Z] Finished install step (success) 16:45:56 INFO - [mozharness: 2017-01-13 00:45:56.613709Z] Running run-tests step. 16:45:56 INFO - Running pre-action listener: _resource_record_pre_action 16:45:56 INFO - Running main action method: run_tests 16:45:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:45:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:45:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:45:56 INFO - 2017-01-12 16:45:56.628 screenresolution[1627:7423] starting screenresolution argv=screenresolution get 16:45:56 INFO - 2017-01-12 16:45:56.631 screenresolution[1627:7423] Display 0: 1600x1200x32@60 16:45:56 INFO - 2017-01-12 16:45:56.637 screenresolution[1628:7426] starting screenresolution argv=screenresolution list 16:45:56 INFO - Available Modes on Display 0 16:45:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:45:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:45:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:45:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:45:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:45:56 INFO - Intel Iris: 16:45:56 INFO - Chipset Model: Intel Iris 16:45:56 INFO - Type: GPU 16:45:56 INFO - Bus: Built-In 16:45:56 INFO - VRAM (Dynamic, Max): 1536 MB 16:45:56 INFO - Vendor: Intel (0x8086) 16:45:56 INFO - Device ID: 0x0a2e 16:45:56 INFO - Revision ID: 0x0009 16:45:56 INFO - Displays: 16:45:56 INFO - Display: 16:45:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:45:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:45:56 INFO - Display Serial Number: 1600x1200 60 16:45:56 INFO - Main Display: Yes 16:45:56 INFO - Mirror: Off 16:45:56 INFO - Online: Yes 16:45:56 INFO - Rotation: Supported 16:45:56 INFO - Return code: 0 16:45:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:45:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:45:56 INFO - Python 2.7.3 16:45:56 INFO - Return code: 0 16:45:56 INFO - grabbing minidump binary from tooltool 16:45:56 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')]} 16:45:56 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 0x10ca6bd30>, '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 0x10c8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:45:56 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 16:45:56 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 16:45:56 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 16:45:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:45:56 INFO - Return code: 0 16:45:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:45:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:45:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:45:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:45:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:45:56 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:45:56 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-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0163 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:45:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0NyIJxubzp/Render', 16:45:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:56 INFO - 'HOME': '/Users/cltbld', 16:45:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:56 INFO - 'LOGNAME': 'cltbld', 16:45:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:45:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:45:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:56 INFO - 'MOZ_NO_REMOTE': '1', 16:45:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:45:56 INFO - 'NO_EM_RESTART': '1', 16:45:56 INFO - 'PAGER': '/bin/cat', 16:45:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:57 INFO - 'PWD': '/builds/slave/test', 16:45:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:45:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:57 INFO - 'SHELL': '/bin/bash', 16:45:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTbyJTO8O7/Listeners', 16:45:57 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:45:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:57 INFO - 'USER': 'cltbld', 16:45:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:57 INFO - 'XPC_FLAGS': '0x0', 16:45:57 INFO - 'XPC_SERVICE_NAME': '0', 16:45:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:45:57 INFO - mozversion application_buildid: 20170112144117 16:45:57 INFO - mozversion application_changeset: 5be69f2ff0164ed5e53ac7fd70f88b220a1d6ade 16:45:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:45:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:45:57 INFO - mozversion application_name: Firefox 16:45:57 INFO - mozversion application_remotingname: firefox-dev 16:45:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:45:57 INFO - mozversion application_vendor: Mozilla 16:45:57 INFO - mozversion application_version: 52.0a2 16:45:57 INFO - mozversion platform_buildid: 20170112144117 16:45:57 INFO - mozversion platform_changeset: 5be69f2ff0164ed5e53ac7fd70f88b220a1d6ade 16:45:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:45:57 INFO - mozversion platform_version: 52.0a2 16:45:57 INFO - using testdate: 1484268357 16:45:57 INFO - actual date: 1484268357 16:45:57 INFO - starting webserver on 'localhost:49245' 16:45:57 INFO - SUITE-START | Running 2 tests 16:45:57 INFO - TEST-START | dromaeo_css 16:45:57 INFO - operating with platform_type : mac_ 16:45:57 INFO - Initialising browser for dromaeo_css test... 16:45:57 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpaDU4kV/profile http://localhost:49245/getInfo.html) 16:46:00 INFO - PROCESS | 1638 | 2017-01-12 16:46:00.631 plugin-container[1640:7598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 16:46:00 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:46:00 INFO - PROCESS | 1638 | 2017-01-12 16:46:00.634 plugin-container[1640:7598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 16:46:00 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:46:00 INFO - PROCESS | 1638 | 2017-01-12 16:46:00.634 plugin-container[1640:7598] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:46:00 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 16:46:00 INFO - PROCESS | 1638 | colorDepth:24 16:46:00 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 16:46:00 INFO - PROCESS | 1638 | __metrics 16:46:00 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 16:46:01 INFO - TEST-INFO | 1638: exit 0 16:46:01 INFO - Browser initialized. 16:46:01 INFO - Running cycle 1/1 for dromaeo_css test... 16:46:01 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpaDU4kV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:46:04 INFO - PROCESS | 1641 | 2017-01-12 16:46:04.312 plugin-container[1642:7726] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 16:46:04 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:46:04 INFO - PROCESS | 1641 | 2017-01-12 16:46:04.315 plugin-container[1642:7726] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 16:46:04 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:46:04 INFO - PROCESS | 1641 | 2017-01-12 16:46:04.315 plugin-container[1642:7726] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:46:13 INFO - PROCESS | 1641 | RSS: Main: 228950016 16:46:13 INFO - PROCESS | 1641 | 16:49:16 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 16:49:18 INFO - PROCESS | 1641 | RSS: Main: 233000960 16:49:18 INFO - PROCESS | 1641 | 16:52:20 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 16:52:21 INFO - PROCESS | 1641 | RSS: Main: 246661120 16:52:21 INFO - PROCESS | 1641 | 16:55:23 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 16:55:24 INFO - PROCESS | 1641 | RSS: Main: 250580992 16:55:24 INFO - PROCESS | 1641 | 16:58:25 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 16:58:26 INFO - PROCESS | 1641 | RSS: Main: 249856000 16:58:26 INFO - PROCESS | 1641 | 17:01:29 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 17:01:29 INFO - PROCESS | 1641 | RSS: Main: 253394944 17:01:29 INFO - PROCESS | 1641 | 17:04:32 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 17:04:33 INFO - PROCESS | 1641 | RSS: Main: 254877696 17:04:33 INFO - PROCESS | 1641 | 17:04:33 INFO - PROCESS | 1641 | __start_tp_report 17:04:33 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 17:04:33 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 17:04:33 INFO - PROCESS | 1641 | |i|pagename|runs| 17:04:33 INFO - PROCESS | 1641 | |0;dojo.html;2816.971830281697;3402.5406570113028;3417.367786959411;3424.4007298722718;3474.357243909876;5712.628679135853;5727.369989301179;5739.626924249926;5752.769889204426;5769.451902069307;5735.311762588485;5778.104393818954;5794.449527294911;5805.419458054193;5839.474447299742;5222.190560463135;5233.86915327118;5269.183276592115;5297.761600727977;5348.973255133719;5324.1215199492235;5335.226392173133;5348.358197016363;5348.973255133719;5398.568114550826;5181.481851814807;5186.507281808223;5298.125809241471;5331.147016477365;5347;5226.738663066832;5244.8688782780355;5273.657212281228;5302.337207849019;5360.437154098802;5183;5259.447757985433;5261.763220655042;5268.92096618551;5273.9736301318635;5542.63972841764;5679.977604031273;5723.170140329629;5752.654840709571;5820.621659592113;6139.631622102644;6171.321154672983;6192.690365481708;6199.87600247997;6214.1921550198185;6235.469985051276;6259.499240060784;6312.968435157794;6324.557280990332;6327.936720632827;5842.620229685057;5882.23530940975;5895.557833162487;5906.49794767441;5935.40645935403;6208.4722798561725;6239.78160764371;6249.812505624838;6294.559380843389;6307.085472606446;6107.541934354852;6122.916604401471;6143.385661433821;6194.34959329273;6250.093736408196;6296.307406185316;6312.4634406076;6315.5894866833505;6321.273053598895;6325.240971083499;6082.118092876596;6130.877382452326;6252.468540174045;6275.058741188767;6298.622082675054;6187.938120618826;6195.783147589903;6211.09939058834;6211.596246243979;6263.655498947511;3886.814521570948;4796.592289655418;4931.58081563064;4973.254011898244;4997.400311962515;3793.715471339661;4378.6278166356215;4428.4243048403505;4438.622717069021;4449.17690227309;5608.887822243532;5754.108113242454;5889;5891.322497912709;5992.610480318765;5622.690752008597;5896.469317761422;6303.621782693053;6330.240371155491;6363.268224154189;5028;5372.811951581754;5751.396103409165;5803.419658034247;5998.070299103646;4883.438404583448;5339.199120131933;5407.432219616883;5410.1343784994215;5428.267183930119;6325.7153428094925;6326.9683651581445;6331.683415829098;6337.7781777637565;6342.7780027700655;6258.249010118815;6284.654344011124;6339.5562310637815;6363.331850155852;6369.45859601939;6252.030935205051;6261.686915654108;6291.245050593775;6330.905036424365;6338.795628830508;6254.8123556293385;6256.311805701278;6265.342139075331;6269.49844012487;6290.779822706183;5166.741662916764;6116.969415152896;6295.716692748746;6297.937020629735;6319.020551814476;5963.52291816645;5996.5502587305255;6133.4786543142545;6158.568900177123;6170.629762214281;6099.634021958696;6139.508839292756;6164.630122192682;6194.969025154845;6204.472619827185;5932.762689492544;6007.369226231182;6007.969960150172;6051.425114614105;6054.515638748978;6008.609440386361;6049.425304596057;6128.203333566787;6214.5960512566535;6219.937800621936;4466.57567531084;5910.70446477683;5979.22270104901;6006.309274433496;6015.669138197266 17:04:33 INFO - PROCESS | 1641 | |1;ext.html;497.2168834086315;504.0297427452156;510.7727061457651;510.77525888609034;529.1058111791073;38774.806125969364;40259.79870100649;40490.79754601227;40564;42542.787286063525;40970.795146024306;41557.792211038904;42018;42068;42071;9403.153716165543;9441.716748497522;9557.808843823119;9576.186024187953;9622.711318660417;9500.097490738368;9595.232381409505;9620.42277463351;9625.037496250388;9633.95183024084;9569.043095690446;9586.13724764771;9597.66408175714;9598.712038638818;9620.086091821266;9465.763355916122;9467.337286389955;9546.474943878084;9588.232941364675;9617.663381781604;9409.294302927308;9453.858192127123;9486.762830929212;9596.23230141587;9646.180074693659;32973.34053318944;39885.80057099725;40285.39571906394;40354;40409.39385909214;3142.4657808172665;3219.344041166047;3415.8121303328307;3657.963420365789;3991.261616600938;28364.574531382044;28939.56590651142;28986.855065724747;29011.854940725374;29861.70138298611;3171.3657268546153;3198.6001749781276;3314.96685033149;3318.0377690469736;3349.827560353881;383868.0806595977;423279;492353;492939;493359;58444;58649.120263196084;58678;58773;58780.41219587835;61605.69197154075;62081.37918620756;62216;62547;62557.68721156365;15924.92037539805;16048.679026419406;16101.953373030716;16168.919155404148;16170.191490425432;12396.442160102817;12443.502259909683;12539.122261441611;12552.058595605366;12572;15824.92087539555;16689.165541722865;16729.414470493717;16757.664846703243;16784.748228776578;3138.9686103138674;6018.4282493163955;6260.843478912972;6264.154339164245;6277.372262773686;808.3613944983515;850.3154960257052;909.2634965677822;928.9024652411534;1111.4387234446715;24960.750392495844;29472.557911631342;29795.55306670402;30384.088477345274;30458.39083218323;928.2295694573488;946.6355453150586;975.4732444480044;1026.8459731040255;1097.5116073347283;14259.429622815178;15360.462383816737;15707.764383534257;16042.598935026717;16342.591435213977;691647;967948;981391;990540;992761.0361948288;57666.13500797492;57914.420855791745;58361.4163858356;58481.70759146177;58695.413045869;1162.9709257268466;1306.1575283941804;1320;1343.2075075705382;1345.838499380061;28993.565096523147;29525.557116642838;30920;31079.912203072785;31477.8426107868;50586;51147.74426127846;51152.48847511626;51207.23189152091;51234.20680276173;549122;643426;648807;649248;650074;26846.463070738868;27217.72782272152;27226.863865680545;27276.863615681796;27316.45367092687;44745.328820068375;45292.77353613211;45523;45869.54130458653;46476.76761616306;37373;37963.810180948916;37989;38079.80960095182;38191.42712859365;37204.25591488101;37859.81070094632;39035;39073;39090.4136437948;36248;36374.8181259092;36585.08537286589;36693.63306366902;38011.619883800806;32619;34173.82913085419;34240.143996399296;34269.97190084301;34322;24936.127235546668;25242.621360679237;25367.61948570736;25644.35889102713;25763 17:04:33 INFO - PROCESS | 1641 | |2;jquery.html;332.61582871783094;363.3695538241151;374.9333147196227;375.5924821568598;393.0154961820639;18368.540786480346;18813;19808.207671693133;19856.801431985677;20045.398638040846;19293.710594341097;19417.126229319678;20036.89981550094;20345.389638310837;20348.98255087247;5386.111291636876;5586.022446071942;5602.1596760486045;5617.241672374238;5619.859503512404;5589.329280486327;5901.940980590203;5928.288605367362;5942.108683697458;6002.96998515009;5694.373618901917;5951.315598706139;5969.432903874125;5982;5997.85005374867;5893.38119497453;5990.9400905990815;6035.6076855004285;6056.515478761733;6066.817995460121;5677.261955945701;5743.885122297573;5814.215080964101;5819.592628516006;5834.299884013945;13050.673733156747;13305.534306299232;13316.134451260734;15494.767578486331;25143.37141571457;2172.2180015194517;2219.87790671513;2221.1226565506536;2863.1983044747503;2932.6040984467036;9536.189423898977;9829.90170098297;9918.40489570628;10200.195870804817;10430.79138417235;1769.0535563473609;1838.8495504813488;1870.9803157279316;1880.2760937039338;1881.4731875075001;104584.43123353156;110960.4451977743;122936;126813;127817.36091319576;11026.062784663232;11519.53921843117;11599.55005624297;11662.475188616532;11884.762304753856;11639.06887449002;12896.35518224085;14440.566782996528;14510.709785804225;14525.636859078397;8102.837943241102;8298.668053277923;8301.8339633207;8400.033996090406;8441.83116337682;8170.673173073129;8223.671053157806;8229.873352003544;8347.747837824374;8483.915160848435;3644.854205831737;3815;4189.811458484375;4395.472543294825;4553.4991150973365;1594.433975938525;1608.3353728890777;1874.250299880059;1929.488685498344;1954.6383918975034;811.8619834628128;855.8801767752519;897.7217062710581;982.1602529837029;986.7335819328885;20413.489662758253;20565.89717051405;20576.794232057488;20696.48258793542;20763.79236207649;885.1237275097607;909.2498688581946;915.5651065743851;925.9490728009889;930.8696782450327;10308.536115874804;10959.671209863716;10998.670039898816;11005.944970275097;11014.00873921351;625590;626279;629583;640967;644116;106563.46718266359;115234;119188.21217681741;137031;138099;779.3336697123991;820.8153165537706;843.1273631791189;963.8120566489467;973.2992245583168;18120.093995300445;18725.063746812877;18844.434666960013;18877.15052822654;18912.054397280357;53728;54158.72920635372;54438;54846.451535484135;57379.71310143422;71875;75348.95812502535;82825.58587207025;83166;83431.27848404912;7605.467617266738;7826.773542287101;7938.722144724907;7982.920170798217;8115.83768324642;11837.993770529602;12250.571230006906;12375;12423;12497.93751031239;8404.789880252798;10533.9473302633;10552.947235263775;10732.678019659423;11035.841236670034;9550.044995500395;9882.30823842353;10344.58663578831;10460.63387781424;10515.421651808918;10759.946200268949;10760.569577217137;10940.781184376108;10993.945030274797;11151.219414640897;7680.692772289046;7903.1306556279915;8000.039995200613;8004.239597238253;8017.318527925192;9083.863742044006;10568.10171135462;10588.841167382341;10695.625653102104;10828.729281768019 17:04:33 INFO - PROCESS | 1641 | |3;mootools.html;1383.5503461375054;1388.9861101388985;1618.9800425731787;1652.8347165283474;1667.8165401805807;7486.438517111211;7740.690372385112;7967.760967170987;8360.414770966036;8441.113683063282;7916.841663166733;8215.91784082159;8219.136990615974;8329;8337.958310208456;2020.8582151084663;2316.4324740438574;2339.543788961153;2344.1443872986406;2350.4123969007746;2309.7690230976937;2325.093213646679;2343.61330380487;2368.0291080656934;2375.5605213035556;1975.8320542753884;2320.431494283903;2334.6264597664376;2339.228054741931;2343.2150229672993;2296.6899468571696;2304.3547806614174;2305.8501197422115;2321.4080409495555;2334.813214942809;2341.519988402373;2372.988135059331;2373.9762602373926;2374.584447721642;2374.78626923576;8337.791555211108;8393.916060839374;8419.663213471455;8461.66153353865;8464.66141354345;5053.46938571452;5218.791248350061;5220.060389129955;5222.8694282642855;5224.03355379256;3295.3574053059424;3428.297199074184;3523.859045638171;3595.4786555949504;3596.8920932371807;1026.343140390151;1048.0661393253076;1088.782243551285;1093.0545078507114;1107.6234080412619;10840.837387439195;10895.237333386665;10943.945280273627;10985.725356866062;10995.175361847814;4509.2559727644575;4631.51369106239;4702.835400761025;4728.739919304402;4750.242410308139;9353.971063183044;9359.62561497546;9446.338756286994;9446.811063778687;9466.006069362755;5170.224466330081;5206.947930520722;5208.114620514521;5217.165253559412;5217.8173763918085;3623.873164439232;4166.125243640314;4726.503717109723;4761.262004389325;4787.640926930494;3825.617438256152;3866.6520013198947;3894.7857867816974;3940.3695408734466;3945.3292940199663;1271.713864380507;1285.9103956071383;1287.4785711786742;1295.313483853559;1299.4087690100982;743.6802175064666;867.3754896474528;899.7120921305251;919.2278486071598;932.5523748600709;5708.65748055118;5845.620034697731;5856.94143058564;5880.705964701748;6068.150458935753;908.6183802802839;970.3207754571687;973.493783232715;985.9112679858847;987.9753006174759;3256.2185075581688;3283.7701360904985;3775.528058992626;3912.4131380292615;3926.6466018058513;9591.376560523544;9923.801523969625;10038.749031274276;10227.02843229892;10229.948850255701;9888.456134912505;9993.200543956465;10171.4914254287;10210.693679189637;10226.53980570876;7273.454490913097;7573.962130189314;7812.062552493623;7822.6088695564995;7852.685892564347;5370.516653501204;5807.128930660435;5892;5968.7612495501435;5982.581219314607;9309.953450232706;9325.906740932774;9433.905660943303;9475.33672642936;9506.61973521079;9276.397034192756;9499.525023748924;9656.517174141125;9678.032196780267;9684.128428441474;6606.372394622503;6798.320167983162;7026.578405295699;7154.642267886481;7284.453665975179;7778.727744528915;8018.118007019107;8033;8040.356771458386;8056.355491560778;7003;7132.429405647639;7173.282671732785;7199.3520583147765;7208.45936554771;6808.080909077211;6915.965420172867;6988.685509152202;7029.578225306498;7063.117110361205;6658.766943156967;6686.966565167143;6733.360330768569;6740.797776066725;6761.188657361148;4913.975430122827;4942.950570494393;4944.110060189057;4975.402951645826;5004.899902002013;4926.137925863031;4939.333190019302;4978.5270399312;5005.7997680092385;5012.04771093491 17:04:33 INFO - PROCESS | 1641 | |4;prototype.html;257.91617724239615;297.7201430655184;303.3039175093161;310.5574556257333;311.85498743084463;5208.921866172011;5324.387695415028;5357.678539287645;5503.229547863298;5521.447855214482;4577.015941572561;5086.821961231358;5227;5339.305890234265;5370.140777475604;3033.135556366436;3566.286742651467;4001.7994601619544;4004.939925901114;4140.441040459529;3436.329915666446;3456.7580269381156;3502.702270307027;4017.27689015977;4142.440770495975;3743.5694895087004;3991.1618560102415;4054.108096218827;4065.0650350419423;4075.531313898895;3992.2614316351423;4010.057636455431;4015.136745599681;4027.114034912344;4054.4729185205883;2921;3498.177928186874;3501.159721666807;3546.007118006962;3566.288181672786;4274.914501709979;4888.87334009707;4995.800167993276;5201.55786758126;5220.504052115043;4205.11692544563;4565.748883811389;4612.746298953556;4652.767361631939;4674.368960190399;4747.774480712169;4805.831795887162;4857.368542089541;5077.923831142536;5323.361196656388;3150.8109513429267;3525.594556625995;3763.190913953486;3836.7530552570524;3845.1540661054523;5140.712382989571;5549.61473501164;5903.834145123017;5914.704264786744;5922.58541902067;3736.3933508591567;3882.6311500407414;3890.8327501749927;3929.25344184604;3952.5691699604536;3264.665763978719;3311.7019468247972;3462.7056700180265;3985.5217373915316;3992.6606238469476;3844.557875844314;4210.642095421924;4436.401085853368;4578.101431468297;4643.373144625432;4441.645927658346;4667.8330417395655;5732.6847023413275;5749.189005463266;5778.711064446761;1139.629620373382;1226.8711785262597;1258.9622311330675;1273.936303184837;1279.643577807527;834.0533494483805;843.8902942617543;870.8998465176459;873.5632183908046;876.2770714160715;3966.353963105279;4783.851875549843;4988.226824842155;4996.275540046673;5111.361079865017;4870.95129048705;4879.926801097987;5378.91931621026;5435.347758269033;5521.840413513127;4520.932186017147;4695.483496815347;4750.883542367541;5070.873228169326;5108.343661296105;2551.9489610207693;2860.7139286071642;2874.3676391193903;2900.303927057533;2905.6513218413925;4898.706077635282;5605.411431799603;5699.373068962411;5727.339071669253;5737.627054241461;4409.669274804402;4736.526347365236;5536.86157846049;5570.749316280777;5696.401877802854;4589.632829373642;5179.689218646893;5240.737963101829;5386.78797270611;5607.271054762937;5418.783248670009;5443.162570387946;5459.808906688247;5502.614816962774;5540.202197890201;4559.4756602989755;4831.748061705817;5391.730413479232;5424.748729277306;5476.892330751936;5503.7798488061635;5585.609007369445;5669.914951275817;5712.42875712442;5735.627184233012;3172;3186.203449137716;3364.848581813775;3737.7570457920147;3744.7378683491893;5124.666896651705;5226.921596175985;5282.735863206901;5343.385510666168;5387.838364849061;5381.946180538301;5423.810166644149;5507.678157242313;5620.578456615688;5664.745086471036;4923.483034281492;5607.691576963306;5647.390493709273;5674.233978412862;5686.488216060575;4880.341153944172;5231.953609278084;5570.58220633446;5577.832665020056;5709.914351284817;5135.922961155511;5611.803586874603;5668.971655141863;5696.772129114789;5739.5695322850115;4342.240107981028;4405.625521830681;4946.62900282473;5182.170852663556;5494.780208791603 17:04:33 INFO - PROCESS | 1641 | |5;yui.html;597.4831770518498;602.0848310567934;699.2064007351654;711.7081996381484;742.2095468326232;13969.371378287977;14778.704425911475;14798.852011479881;14867.851321486796;14891.851081489169;11778.175527713063;13324.866751332484;13510.392032358543;13518.59444216675;13552.593422197326;635.9173307470046;669.6283562622742;695.7634404302523;793.7936136604467;824.3981893217924;813.3168138763438;821.4044817507289;828.9253967142956;830.3896635972555;832.7335252719132;861.7888617288774;879.0813599788237;880.9559522023906;882.9470231786081;885.4996926736405;878.5343767803074;880.8502554565739;880.8898887639045;882.1972005475051;883.7878909061811;651.6155468273741;656.9704363303663;656.9868602627969;659.0410952064734;661.5699795133154;13569.864301356958;13580.38888250031;13784.931075344659;13803.309834508334;14409.927950360287;13651.249181295025;13681.247531385869;13891.02762806604;13931.874622122526;14034.36845341962;11087.057600104;11112.499937502831;11371.204015718902;11413;11422.543098276059;3585.4801053847045;3609.5488063992;3651.5253017107607;3677.1726361568435;3677.9080522987138;94155;95369.56945645821;96251.51874240584;97703.99999999929;98618;10516.526756295985;10781.299215550964;10801.83797243042;10886.836697449544;10993.835092473619;20976.790232097483;21109.78890211109;21177.364679059654;21181.36455906325;21561.460963475725;7613.276738709925;7613.695452181962;7616.200298968529;7699.050692804993;7770.378369730408;3553.4314509678843;4138.151678905817;4156.646685031746;4242.363645453207;4243.978780106141;3271.820049897231;3376.189714468559;3390.7287417006582;3393.253484233465;3398.847051882671;619.2321521313629;625.7747211004034;638.1257676982564;647.873664635401;659.9439047680905;492.4410793749121;563.8703098287417;575.4446958684889;587.8198943714484;597.9312379076375;7906.920930790733;8046;8554.43141234177;8699.782505437415;8718.128187181232;546.6446809573729;576.9105788602774;582.659144400522;584.8245526342081;607.2166904692984;3924.6860251179837;4197.454330936958;4241.024564350217;4314.115606300701;4347;109725.35411968827;113812;116022.83977160288;117836.8216317843;118482.40758796323;96626;97283.51358243164;98778.50610747044;100060;100677.99322006833;89152;89479.55260223788;91625;92615.61076583857;92936;6899.689513971984;8232.794180145544;8298.58507074656;8303.875441868498;8489.193526614961;52330.903542974054;52802.9439411202;58132;58811.70594147002;59933.101003485855;62227;66749.99875001976;70397.9440308406;78373;82189;10178.338408003752;11443.485043173243;11506.94246528762;12508.437120329425;12658.050646201757;13707;13866.584002479942;14322.641933951734;14650.120992740469;15014.47449339268;12581.74836503283;12741.55404560836;13359.933200333937;13478.326083695973;13608.387622557208;11774.94112529432;11803.468843902217;12609.243445393306;13556.525521606696;13924.651883702583;12530.498780048696;13018;13192.604221873358;13504.662383440493;13542.255175965416;9982.700518984442;10277.84583231267;10549;10969;11714.589989350332;11203.327800331681;11371.886281137413;11453.083753299883;11459.08327333828;11466.656000320003 17:04:33 INFO - PROCESS | 1641 | __end_tp_report 17:04:33 INFO - PROCESS | 1641 | __start_cc_report 17:04:33 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,369 17:04:33 INFO - PROCESS | 1641 | __end_cc_report 17:04:33 INFO - PROCESS | 1641 | __startTimestamp1484269473112__endTimestamp 17:04:33 INFO - PROCESS | 1641 | 17:04:33 INFO - PROCESS | 1641 | ------- Summary: start ------- 17:04:33 INFO - PROCESS | 1641 | Number of tests: 6 17:04:33 INFO - PROCESS | 1641 | 17:04:33 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5746.61 Median:5996.55 stddev:682.43 (11.4%) stddev-sans-first:644.66 17:04:33 INFO - PROCESS | 1641 | Values: 2817.0 3402.5 3417.4 3424.4 3474.4 5712.6 5727.4 5739.6 5752.8 5769.5 5735.3 5778.1 5794.4 5805.4 5839.5 5222.2 5233.9 5269.2 5297.8 5349.0 5324.1 5335.2 5348.4 5349.0 5398.6 5181.5 5186.5 5298.1 5331.1 5347.0 5226.7 5244.9 5273.7 5302.3 5360.4 5183.0 5259.4 5261.8 5268.9 5274.0 5542.6 5680.0 5723.2 5752.7 5820.6 6139.6 6171.3 6192.7 6199.9 6214.2 6235.5 6259.5 6313.0 6324.6 6327.9 5842.6 5882.2 5895.6 5906.5 5935.4 6208.5 6239.8 6249.8 6294.6 6307.1 6107.5 6122.9 6143.4 6194.3 6250.1 6296.3 6312.5 6315.6 6321.3 6325.2 6082.1 6130.9 6252.5 6275.1 6298.6 6187.9 6195.8 6211.1 6211.6 6263.7 3886.8 4796.6 4931.6 4973.3 4997.4 3793.7 4378.6 4428.4 4438.6 4449.2 5608.9 5754.1 5889.0 5891.3 5992.6 5622.7 5896.5 6303.6 6330.2 6363.3 5028.0 5372.8 5751.4 5803.4 5998.1 4883.4 5339.2 5407.4 5410.1 5428.3 6325.7 6327.0 6331.7 6337.8 6342.8 6258.2 6284.7 6339.6 6363.3 6369.5 6252.0 6261.7 6291.2 6330.9 6338.8 6254.8 6256.3 6265.3 6269.5 6290.8 5166.7 6117.0 6295.7 6297.9 6319.0 5963.5 5996.6 6133.5 6158.6 6170.6 6099.6 6139.5 6164.6 6195.0 6204.5 5932.8 6007.4 6008.0 6051.4 6054.5 6008.6 6049.4 6128.2 6214.6 6219.9 4466.6 5910.7 5979.2 6006.3 6015.7 17:04:33 INFO - PROCESS | 1641 | 17:04:33 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:78320.76 Median:40381.70 stddev:189570.37 (469.4%) stddev-sans-first:190012.11 17:04:33 INFO - PROCESS | 1641 | Values: 497.2 504.0 510.8 510.8 529.1 38774.8 40259.8 40490.8 40564.0 42542.8 40970.8 41557.8 42018.0 42068.0 42071.0 9403.2 9441.7 9557.8 9576.2 9622.7 9500.1 9595.2 9620.4 9625.0 9634.0 9569.0 9586.1 9597.7 9598.7 9620.1 9465.8 9467.3 9546.5 9588.2 9617.7 9409.3 9453.9 9486.8 9596.2 9646.2 32973.3 39885.8 40285.4 40354.0 40409.4 3142.5 3219.3 3415.8 3658.0 3991.3 28364.6 28939.6 28986.9 29011.9 29861.7 3171.4 3198.6 3315.0 3318.0 3349.8 383868.1 423279.0 492353.0 492939.0 493359.0 58444.0 58649.1 58678.0 58773.0 58780.4 61605.7 62081.4 62216.0 62547.0 62557.7 15924.9 16048.7 16102.0 16168.9 16170.2 12396.4 12443.5 12539.1 12552.1 12572.0 15824.9 16689.2 16729.4 16757.7 16784.7 3139.0 6018.4 6260.8 6264.2 6277.4 808.4 850.3 909.3 928.9 1111.4 24960.8 29472.6 29795.6 30384.1 30458.4 928.2 946.6 975.5 1026.8 1097.5 14259.4 15360.5 15707.8 16042.6 16342.6 691647.0 967948.0 981391.0 990540.0 992761.0 57666.1 57914.4 58361.4 58481.7 58695.4 1163.0 1306.2 1320.0 1343.2 1345.8 28993.6 29525.6 30920.0 31079.9 31477.8 50586.0 51147.7 51152.5 51207.2 51234.2 549122.0 643426.0 648807.0 649248.0 650074.0 26846.5 27217.7 27226.9 27276.9 27316.5 44745.3 45292.8 45523.0 45869.5 46476.8 37373.0 37963.8 37989.0 38079.8 38191.4 37204.3 37859.8 39035.0 39073.0 39090.4 36248.0 36374.8 36585.1 36693.6 38011.6 32619.0 34173.8 34240.1 34270.0 34322.0 24936.1 25242.6 25367.6 25644.4 25763.0 17:04:33 INFO - PROCESS | 1641 | 17:04:33 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:35522.70 Median:2009.89 stddev:105432.41 (5245.7%) stddev-sans-first:105695.06 17:04:33 INFO - PROCESS | 1641 | Values: 332.6 363.4 374.9 375.6 393.0 18368.5 18813.0 19808.2 19856.8 20045.4 19293.7 19417.1 20036.9 20345.4 20349.0 5386.1 5586.0 5602.2 5617.2 5619.9 5589.3 5901.9 5928.3 5942.1 6003.0 5694.4 5951.3 5969.4 5982.0 5997.9 5893.4 5990.9 6035.6 6056.5 6066.8 5677.3 5743.9 5814.2 5819.6 5834.3 13050.7 13305.5 13316.1 15494.8 25143.4 2172.2 2219.9 2221.1 2863.2 2932.6 9536.2 9829.9 9918.4 10200.2 10430.8 1769.1 1838.8 1871.0 1880.3 1881.5 104584.4 110960.4 122936.0 126813.0 127817.4 11026.1 11519.5 11599.6 11662.5 11884.8 11639.1 12896.4 14440.6 14510.7 14525.6 8102.8 8298.7 8301.8 8400.0 8441.8 8170.7 8223.7 8229.9 8347.7 8483.9 3644.9 3815.0 4189.8 4395.5 4553.5 1594.4 1608.3 1874.3 1929.5 1954.6 811.9 855.9 897.7 982.2 986.7 20413.5 20565.9 20576.8 20696.5 20763.8 885.1 909.2 915.6 925.9 930.9 10308.5 10959.7 10998.7 11005.9 11014.0 625590.0 626279.0 629583.0 640967.0 644116.0 106563.5 115234.0 119188.2 137031.0 138099.0 779.3 820.8 843.1 963.8 973.3 18120.1 18725.1 18844.4 18877.2 18912.1 53728.0 54158.7 54438.0 54846.5 57379.7 71875.0 75349.0 82825.6 83166.0 83431.3 7605.5 7826.8 7938.7 7982.9 8115.8 11838.0 12250.6 12375.0 12423.0 12497.9 8404.8 10533.9 10552.9 10732.7 11035.8 9550.0 9882.3 10344.6 10460.6 10515.4 10759.9 10760.6 10940.8 10993.9 11151.2 7680.7 7903.1 8000.0 8004.2 8017.3 9083.9 10568.1 10588.8 10695.6 10828.7 17:04:33 INFO - PROCESS | 1641 | 17:04:33 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5468.67 Median:5217.49 stddev:2985.21 (57.2%) stddev-sans-first:2977.79 17:04:33 INFO - PROCESS | 1641 | Values: 1383.6 1389.0 1619.0 1652.8 1667.8 7486.4 7740.7 7967.8 8360.4 8441.1 7916.8 8215.9 8219.1 8329.0 8338.0 2020.9 2316.4 2339.5 2344.1 2350.4 2309.8 2325.1 2343.6 2368.0 2375.6 1975.8 2320.4 2334.6 2339.2 2343.2 2296.7 2304.4 2305.9 2321.4 2334.8 2341.5 2373.0 2374.0 2374.6 2374.8 8337.8 8393.9 8419.7 8461.7 8464.7 5053.5 5218.8 5220.1 5222.9 5224.0 3295.4 3428.3 3523.9 3595.5 3596.9 1026.3 1048.1 1088.8 1093.1 1107.6 10840.8 10895.2 10943.9 10985.7 10995.2 4509.3 4631.5 4702.8 4728.7 4750.2 9354.0 9359.6 9446.3 9446.8 9466.0 5170.2 5206.9 5208.1 5217.2 5217.8 3623.9 4166.1 4726.5 4761.3 4787.6 3825.6 3866.7 3894.8 3940.4 3945.3 1271.7 1285.9 1287.5 1295.3 1299.4 743.7 867.4 899.7 919.2 932.6 5708.7 5845.6 5856.9 5880.7 6068.2 908.6 970.3 973.5 985.9 988.0 3256.2 3283.8 3775.5 3912.4 3926.6 9591.4 9923.8 10038.7 10227.0 10229.9 9888.5 9993.2 10171.5 10210.7 10226.5 7273.5 7574.0 7812.1 7822.6 7852.7 5370.5 5807.1 5892.0 5968.8 5982.6 9310.0 9325.9 9433.9 9475.3 9506.6 9276.4 9499.5 9656.5 9678.0 9684.1 6606.4 6798.3 7026.6 7154.6 7284.5 7778.7 8018.1 8033.0 8040.4 8056.4 7003.0 7132.4 7173.3 7199.4 7208.5 6808.1 6916.0 6988.7 7029.6 7063.1 6658.8 6687.0 6733.4 6740.8 6761.2 4914.0 4943.0 4944.1 4975.4 5004.9 4926.1 4939.3 4978.5 5005.8 5012.0 17:04:33 INFO - PROCESS | 1641 | 17:04:33 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4388.58 Median:4880.13 stddev:1362.10 (27.9%) stddev-sans-first:1330.17 17:04:33 INFO - PROCESS | 1641 | Values: 257.9 297.7 303.3 310.6 311.9 5208.9 5324.4 5357.7 5503.2 5521.4 4577.0 5086.8 5227.0 5339.3 5370.1 3033.1 3566.3 4001.8 4004.9 4140.4 3436.3 3456.8 3502.7 4017.3 4142.4 3743.6 3991.2 4054.1 4065.1 4075.5 3992.3 4010.1 4015.1 4027.1 4054.5 2921.0 3498.2 3501.2 3546.0 3566.3 4274.9 4888.9 4995.8 5201.6 5220.5 4205.1 4565.7 4612.7 4652.8 4674.4 4747.8 4805.8 4857.4 5077.9 5323.4 3150.8 3525.6 3763.2 3836.8 3845.2 5140.7 5549.6 5903.8 5914.7 5922.6 3736.4 3882.6 3890.8 3929.3 3952.6 3264.7 3311.7 3462.7 3985.5 3992.7 3844.6 4210.6 4436.4 4578.1 4643.4 4441.6 4667.8 5732.7 5749.2 5778.7 1139.6 1226.9 1259.0 1273.9 1279.6 834.1 843.9 870.9 873.6 876.3 3966.4 4783.9 4988.2 4996.3 5111.4 4871.0 4879.9 5378.9 5435.3 5521.8 4520.9 4695.5 4750.9 5070.9 5108.3 2551.9 2860.7 2874.4 2900.3 2905.7 4898.7 5605.4 5699.4 5727.3 5737.6 4409.7 4736.5 5536.9 5570.7 5696.4 4589.6 5179.7 5240.7 5386.8 5607.3 5418.8 5443.2 5459.8 5502.6 5540.2 4559.5 4831.7 5391.7 5424.7 5476.9 5503.8 5585.6 5669.9 5712.4 5735.6 3172.0 3186.2 3364.8 3737.8 3744.7 5124.7 5226.9 5282.7 5343.4 5387.8 5381.9 5423.8 5507.7 5620.6 5664.7 4923.5 5607.7 5647.4 5674.2 5686.5 4880.3 5232.0 5570.6 5577.8 5709.9 5135.9 5611.8 5669.0 5696.8 5739.6 4342.2 4405.6 4946.6 5182.2 5494.8 17:04:33 INFO - PROCESS | 1641 | 17:04:33 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:21151.97 Median:4219.24 stddev:31608.57 (749.2%) stddev-sans-first:31659.57 17:04:33 INFO - PROCESS | 1641 | Values: 597.5 602.1 699.2 711.7 742.2 13969.4 14778.7 14798.9 14867.9 14891.9 11778.2 13324.9 13510.4 13518.6 13552.6 635.9 669.6 695.8 793.8 824.4 813.3 821.4 828.9 830.4 832.7 861.8 879.1 881.0 882.9 885.5 878.5 880.9 880.9 882.2 883.8 651.6 657.0 657.0 659.0 661.6 13569.9 13580.4 13784.9 13803.3 14409.9 13651.2 13681.2 13891.0 13931.9 14034.4 11087.1 11112.5 11371.2 11413.0 11422.5 3585.5 3609.5 3651.5 3677.2 3677.9 94155.0 95369.6 96251.5 97704.0 98618.0 10516.5 10781.3 10801.8 10886.8 10993.8 20976.8 21109.8 21177.4 21181.4 21561.5 7613.3 7613.7 7616.2 7699.1 7770.4 3553.4 4138.2 4156.6 4242.4 4244.0 3271.8 3376.2 3390.7 3393.3 3398.8 619.2 625.8 638.1 647.9 659.9 492.4 563.9 575.4 587.8 597.9 7906.9 8046.0 8554.4 8699.8 8718.1 546.6 576.9 582.7 584.8 607.2 3924.7 4197.5 4241.0 4314.1 4347.0 109725.4 113812.0 116022.8 117836.8 118482.4 96626.0 97283.5 98778.5 100060.0 100678.0 89152.0 89479.6 91625.0 92615.6 92936.0 6899.7 8232.8 8298.6 8303.9 8489.2 52330.9 52802.9 58132.0 58811.7 59933.1 62227.0 66750.0 70397.9 78373.0 82189.0 10178.3 11443.5 11506.9 12508.4 12658.1 13707.0 13866.6 14322.6 14650.1 15014.5 12581.7 12741.6 13359.9 13478.3 13608.4 11774.9 11803.5 12609.2 13556.5 13924.7 12530.5 13018.0 13192.6 13504.7 13542.3 9982.7 10277.8 10549.0 10969.0 11714.6 11203.3 11371.9 11453.1 11459.1 11466.7 17:04:33 INFO - PROCESS | 1641 | -------- Summary: end -------- 17:04:33 INFO - PROCESS | 1641 | 17:04:33 INFO - TEST-INFO | 1641: exit 0 17:04:33 INFO - TEST-OK | dromaeo_css | took 1116561ms 17:04:33 INFO - TEST-START | kraken 17:04:33 INFO - operating with platform_type : mac_ 17:04:33 INFO - Initialising browser for kraken test... 17:04:33 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpp3xYt5/profile http://localhost:49245/getInfo.html) 17:04:36 INFO - PROCESS | 1652 | 2017-01-12 17:04:36.664 plugin-container[1653:10097] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8543, name = 'com.apple.tsm.portname' 17:04:36 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:36 INFO - PROCESS | 1652 | 2017-01-12 17:04:36.669 plugin-container[1653:10097] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 17:04:36 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:36 INFO - PROCESS | 1652 | 2017-01-12 17:04:36.669 plugin-container[1653:10097] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:04:36 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 17:04:36 INFO - PROCESS | 1652 | colorDepth:24 17:04:36 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 17:04:36 INFO - PROCESS | 1652 | __metrics 17:04:36 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 17:04:37 INFO - TEST-INFO | 1652: exit 0 17:04:37 INFO - Browser initialized. 17:04:37 INFO - Running cycle 1/1 for kraken test... 17:04:37 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpp3xYt5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:04:40 INFO - PROCESS | 1654 | 2017-01-12 17:04:40.141 plugin-container[1655:10223] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 17:04:40 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:40 INFO - PROCESS | 1654 | 2017-01-12 17:04:40.142 plugin-container[1655:10223] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x881f, name = 'com.apple.CFPasteboardClient' 17:04:40 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:40 INFO - PROCESS | 1654 | 2017-01-12 17:04:40.142 plugin-container[1655:10223] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:04:49 INFO - PROCESS | 1654 | RSS: Main: 229105664 17:04:49 INFO - PROCESS | 1654 | 17:04:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 17:04:53 INFO - PROCESS | 1654 | RSS: Main: 241242112 17:04:53 INFO - PROCESS | 1654 | 17:04:56 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 17:04:56 INFO - PROCESS | 1654 | RSS: Main: 249409536 17:04:56 INFO - PROCESS | 1654 | 17:05:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 17:05:00 INFO - PROCESS | 1654 | RSS: Main: 253243392 17:05:00 INFO - PROCESS | 1654 | 17:05:02 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 17:05:03 INFO - PROCESS | 1654 | RSS: Main: 253476864 17:05:03 INFO - PROCESS | 1654 | 17:05:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:05:05 INFO - PROCESS | 1654 | RSS: Main: 253403136 17:05:05 INFO - PROCESS | 1654 | 17:05:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 17:05:09 INFO - PROCESS | 1654 | RSS: Main: 258834432 17:05:09 INFO - PROCESS | 1654 | 17:05:12 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 17:05:13 INFO - PROCESS | 1654 | RSS: Main: 258088960 17:05:13 INFO - PROCESS | 1654 | 17:05:16 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 17:05:17 INFO - PROCESS | 1654 | RSS: Main: 258056192 17:05:17 INFO - PROCESS | 1654 | 17:05:18 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:05:19 INFO - PROCESS | 1654 | RSS: Main: 257388544 17:05:19 INFO - PROCESS | 1654 | 17:05:21 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:05:22 INFO - PROCESS | 1654 | RSS: Main: 257417216 17:05:22 INFO - PROCESS | 1654 | 17:05:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:05:24 INFO - PROCESS | 1654 | RSS: Main: 257318912 17:05:24 INFO - PROCESS | 1654 | 17:05:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:05:28 INFO - PROCESS | 1654 | RSS: Main: 257744896 17:05:28 INFO - PROCESS | 1654 | 17:05:31 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:05:32 INFO - PROCESS | 1654 | RSS: Main: 257875968 17:05:32 INFO - PROCESS | 1654 | 17:05:33 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 17:05:34 INFO - PROCESS | 1654 | RSS: Main: 259870720 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | __start_tp_report 17:05:34 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 17:05:34 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 17:05:34 INFO - PROCESS | 1654 | |i|pagename|runs| 17:05:34 INFO - PROCESS | 1654 | |0;ai-astar;107;118;117;120;118;117;150;149;118;120 17:05:34 INFO - PROCESS | 1654 | |1;audio-beat-detection;144;115;111;145;113;115;112;113;141;113 17:05:34 INFO - PROCESS | 1654 | |2;audio-dft;196;199;198;205;201;194;241;256;196;204 17:05:34 INFO - PROCESS | 1654 | |3;audio-fft;80;61;59;89;61;60;60;61;80;62 17:05:34 INFO - PROCESS | 1654 | |4;audio-oscillator;88;71;86;74;70;72;85;70;69;92 17:05:34 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;112;107;138;135;106;86;92;87;91;89 17:05:34 INFO - PROCESS | 1654 | |6;imaging-darkroom;91;137;105;156;146;154;95;97;97;95 17:05:34 INFO - PROCESS | 1654 | |7;imaging-desaturate;87;88;92;130;144;132;110;76;124;76 17:05:34 INFO - PROCESS | 1654 | |8;json-parse-financial;56;57;60;61;61;60;59;57;63;61 17:05:34 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;74;68;68;109;117;108;107;107;73;73 17:05:34 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;83;86;82;82;93;77;94;79;84;104 17:05:34 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;142;141;141;142;141;130;138;145;189;153 17:05:34 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;150;209;221;164;220;193;169;168;166;191 17:05:34 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;67;69;71;74;91;87;68;70;67;69 17:05:34 INFO - PROCESS | 1654 | __end_tp_report 17:05:34 INFO - PROCESS | 1654 | __start_cc_report 17:05:34 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,962 17:05:34 INFO - PROCESS | 1654 | __end_cc_report 17:05:34 INFO - PROCESS | 1654 | __startTimestamp1484269534073__endTimestamp 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | ------- Summary: start ------- 17:05:34 INFO - PROCESS | 1654 | Number of tests: 14 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:123.40 Median:119.00 stddev:14.24 (12.0%) stddev-sans-first:13.81 17:05:34 INFO - PROCESS | 1654 | Values: 107.0 118.0 117.0 120.0 118.0 117.0 150.0 149.0 118.0 120.0 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:122.20 Median:115.00 stddev:14.67 (12.8%) stddev-sans-first:13.26 17:05:34 INFO - PROCESS | 1654 | Values: 144.0 115.0 111.0 145.0 113.0 115.0 112.0 113.0 141.0 113.0 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:209.00 Median:202.50 stddev:21.40 (10.6%) stddev-sans-first:22.18 17:05:34 INFO - PROCESS | 1654 | Values: 196.0 199.0 198.0 205.0 201.0 194.0 241.0 256.0 196.0 204.0 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:67.30 Median:61.50 stddev:11.14 (18.1%) stddev-sans-first:10.82 17:05:34 INFO - PROCESS | 1654 | Values: 80.0 61.0 59.0 89.0 61.0 60.0 60.0 61.0 80.0 62.0 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:79.50 stddev:8.93 (11.2%) stddev-sans-first:8.66 17:05:34 INFO - PROCESS | 1654 | Values: 88.0 71.0 86.0 74.0 70.0 72.0 85.0 70.0 69.0 92.0 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:104.30 Median:86.50 stddev:19.28 (22.3%) stddev-sans-first:20.24 17:05:34 INFO - PROCESS | 1654 | Values: 112.0 107.0 138.0 135.0 106.0 86.0 92.0 87.0 91.0 89.0 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:117.30 Median:93.00 stddev:27.32 (29.4%) stddev-sans-first:27.27 17:05:34 INFO - PROCESS | 1654 | Values: 91.0 137.0 105.0 156.0 146.0 154.0 95.0 97.0 97.0 95.0 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:105.90 Median:76.00 stddev:25.20 (33.2%) stddev-sans-first:25.79 17:05:34 INFO - PROCESS | 1654 | Values: 87.0 88.0 92.0 130.0 144.0 132.0 110.0 76.0 124.0 76.0 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:59.50 Median:60.50 stddev:2.22 (3.7%) stddev-sans-first:1.96 17:05:34 INFO - PROCESS | 1654 | Values: 56.0 57.0 60.0 61.0 61.0 60.0 59.0 57.0 63.0 61.0 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:90.40 Median:68.00 stddev:20.53 (30.2%) stddev-sans-first:20.90 17:05:34 INFO - PROCESS | 1654 | Values: 74.0 68.0 68.0 109.0 117.0 108.0 107.0 107.0 73.0 73.0 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:83.50 stddev:8.24 (9.9%) stddev-sans-first:8.64 17:05:34 INFO - PROCESS | 1654 | Values: 83.0 86.0 82.0 82.0 93.0 77.0 94.0 79.0 84.0 104.0 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:146.20 Median:142.00 stddev:16.07 (11.3%) stddev-sans-first:16.98 17:05:34 INFO - PROCESS | 1654 | Values: 142.0 141.0 141.0 142.0 141.0 130.0 138.0 145.0 189.0 153.0 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.10 Median:192.00 stddev:25.32 (13.2%) stddev-sans-first:23.45 17:05:34 INFO - PROCESS | 1654 | Values: 150.0 209.0 221.0 164.0 220.0 193.0 169.0 168.0 166.0 191.0 17:05:34 INFO - PROCESS | 1654 | 17:05:34 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:70.50 stddev:8.58 (12.2%) stddev-sans-first:8.79 17:05:34 INFO - PROCESS | 1654 | Values: 67.0 69.0 71.0 74.0 91.0 87.0 68.0 70.0 67.0 69.0 17:05:34 INFO - PROCESS | 1654 | -------- Summary: end -------- 17:05:34 INFO - PROCESS | 1654 | 17:05:35 INFO - TEST-INFO | 1654: exit 0 17:05:35 INFO - TEST-OK | kraken | took 61334ms 17:05:35 INFO - SUITE-END | took 1177s 17:05:35 INFO - Completed test suite (00:19:38) 17:05:35 INFO - javascript benchmark 17:05:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7137.357856769987, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2816.971830281697, 3402.5406570113028, 3417.367786959411, 3424.4007298722718, 3474.357243909876, 5712.628679135853, 5727.369989301179, 5739.626924249926, 5752.769889204426, 5769.451902069307, 5735.311762588485, 5778.104393818954, 5794.449527294911, 5805.419458054193, 5839.474447299742, 5222.190560463135, 5233.86915327118, 5269.183276592115, 5297.761600727977, 5348.973255133719, 5324.1215199492235, 5335.226392173133, 5348.358197016363, 5348.973255133719, 5398.568114550826, 5181.481851814807, 5186.507281808223, 5298.125809241471, 5331.147016477365, 5347.0, 5226.738663066832, 5244.8688782780355, 5273.657212281228, 5302.337207849019, 5360.437154098802, 5183.0, 5259.447757985433, 5261.763220655042, 5268.92096618551, 5273.9736301318635, 5542.63972841764, 5679.977604031273, 5723.170140329629, 5752.654840709571, 5820.621659592113, 6139.631622102644, 6171.321154672983, 6192.690365481708, 6199.87600247997, 6214.1921550198185, 6235.469985051276, 6259.499240060784, 6312.968435157794, 6324.557280990332, 6327.936720632827, 5842.620229685057, 5882.23530940975, 5895.557833162487, 5906.49794767441, 5935.40645935403, 6208.4722798561725, 6239.78160764371, 6249.812505624838, 6294.559380843389, 6307.085472606446, 6107.541934354852, 6122.916604401471, 6143.385661433821, 6194.34959329273, 6250.093736408196, 6296.307406185316, 6312.4634406076, 6315.5894866833505, 6321.273053598895, 6325.240971083499, 6082.118092876596, 6130.877382452326, 6252.468540174045, 6275.058741188767, 6298.622082675054, 6187.938120618826, 6195.783147589903, 6211.09939058834, 6211.596246243979, 6263.655498947511, 3886.814521570948, 4796.592289655418, 4931.58081563064, 4973.254011898244, 4997.400311962515, 3793.715471339661, 4378.6278166356215, 4428.4243048403505, 4438.622717069021, 4449.17690227309, 5608.887822243532, 5754.108113242454, 5889.0, 5891.322497912709, 5992.610480318765, 5622.690752008597, 5896.469317761422, 6303.621782693053, 6330.240371155491, 6363.268224154189, 5028.0, 5372.811951581754, 5751.396103409165, 5803.419658034247, 5998.070299103646, 4883.438404583448, 5339.199120131933, 5407.432219616883, 5410.1343784994215, 5428.267183930119, 6325.7153428094925, 6326.9683651581445, 6331.683415829098, 6337.7781777637565, 6342.7780027700655, 6258.249010118815, 6284.654344011124, 6339.5562310637815, 6363.331850155852, 6369.45859601939, 6252.030935205051, 6261.686915654108, 6291.245050593775, 6330.905036424365, 6338.795628830508, 6254.8123556293385, 6256.311805701278, 6265.342139075331, 6269.49844012487, 6290.779822706183, 5166.741662916764, 6116.969415152896, 6295.716692748746, 6297.937020629735, 6319.020551814476, 5963.52291816645, 5996.5502587305255, 6133.4786543142545, 6158.568900177123, 6170.629762214281, 6099.634021958696, 6139.508839292756, 6164.630122192682, 6194.969025154845, 6204.472619827185, 5932.762689492544, 6007.369226231182, 6007.969960150172, 6051.425114614105, 6054.515638748978, 6008.609440386361, 6049.425304596057, 6128.203333566787, 6214.5960512566535, 6219.937800621936, 4466.57567531084, 5910.70446477683, 5979.22270104901, 6006.309274433496, 6015.669138197266], "value": 5701.8351170346, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.2168834086315, 504.0297427452156, 510.7727061457651, 510.77525888609034, 529.1058111791073, 38774.806125969364, 40259.79870100649, 40490.79754601227, 40564.0, 42542.787286063525, 40970.795146024306, 41557.792211038904, 42018.0, 42068.0, 42071.0, 9403.153716165543, 9441.716748497522, 9557.808843823119, 9576.186024187953, 9622.711318660417, 9500.097490738368, 9595.232381409505, 9620.42277463351, 9625.037496250388, 9633.95183024084, 9569.043095690446, 9586.13724764771, 9597.66408175714, 9598.712038638818, 9620.086091821266, 9465.763355916122, 9467.337286389955, 9546.474943878084, 9588.232941364675, 9617.663381781604, 9409.294302927308, 9453.858192127123, 9486.762830929212, 9596.23230141587, 9646.180074693659, 32973.34053318944, 39885.80057099725, 40285.39571906394, 40354.0, 40409.39385909214, 3142.4657808172665, 3219.344041166047, 3415.8121303328307, 3657.963420365789, 3991.261616600938, 28364.574531382044, 28939.56590651142, 28986.855065724747, 29011.854940725374, 29861.70138298611, 3171.3657268546153, 3198.6001749781276, 3314.96685033149, 3318.0377690469736, 3349.827560353881, 383868.0806595977, 423279.0, 492353.0, 492939.0, 493359.0, 58444.0, 58649.120263196084, 58678.0, 58773.0, 58780.41219587835, 61605.69197154075, 62081.37918620756, 62216.0, 62547.0, 62557.68721156365, 15924.92037539805, 16048.679026419406, 16101.953373030716, 16168.919155404148, 16170.191490425432, 12396.442160102817, 12443.502259909683, 12539.122261441611, 12552.058595605366, 12572.0, 15824.92087539555, 16689.165541722865, 16729.414470493717, 16757.664846703243, 16784.748228776578, 3138.9686103138674, 6018.4282493163955, 6260.843478912972, 6264.154339164245, 6277.372262773686, 808.3613944983515, 850.3154960257052, 909.2634965677822, 928.9024652411534, 1111.4387234446715, 24960.750392495844, 29472.557911631342, 29795.55306670402, 30384.088477345274, 30458.39083218323, 928.2295694573488, 946.6355453150586, 975.4732444480044, 1026.8459731040255, 1097.5116073347283, 14259.429622815178, 15360.462383816737, 15707.764383534257, 16042.598935026717, 16342.591435213977, 691647.0, 967948.0, 981391.0, 990540.0, 992761.0361948288, 57666.13500797492, 57914.420855791745, 58361.4163858356, 58481.70759146177, 58695.413045869, 1162.9709257268466, 1306.1575283941804, 1320.0, 1343.2075075705382, 1345.838499380061, 28993.565096523147, 29525.557116642838, 30920.0, 31079.912203072785, 31477.8426107868, 50586.0, 51147.74426127846, 51152.48847511626, 51207.23189152091, 51234.20680276173, 549122.0, 643426.0, 648807.0, 649248.0, 650074.0, 26846.463070738868, 27217.72782272152, 27226.863865680545, 27276.863615681796, 27316.45367092687, 44745.328820068375, 45292.77353613211, 45523.0, 45869.54130458653, 46476.76761616306, 37373.0, 37963.810180948916, 37989.0, 38079.80960095182, 38191.42712859365, 37204.25591488101, 37859.81070094632, 39035.0, 39073.0, 39090.4136437948, 36248.0, 36374.8181259092, 36585.08537286589, 36693.63306366902, 38011.619883800806, 32619.0, 34173.82913085419, 34240.143996399296, 34269.97190084301, 34322.0, 24936.127235546668, 25242.621360679237, 25367.61948570736, 25644.35889102713, 25763.0], "value": 19757.4622667748, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.61582871783094, 363.3695538241151, 374.9333147196227, 375.5924821568598, 393.0154961820639, 18368.540786480346, 18813.0, 19808.207671693133, 19856.801431985677, 20045.398638040846, 19293.710594341097, 19417.126229319678, 20036.89981550094, 20345.389638310837, 20348.98255087247, 5386.111291636876, 5586.022446071942, 5602.1596760486045, 5617.241672374238, 5619.859503512404, 5589.329280486327, 5901.940980590203, 5928.288605367362, 5942.108683697458, 6002.96998515009, 5694.373618901917, 5951.315598706139, 5969.432903874125, 5982.0, 5997.85005374867, 5893.38119497453, 5990.9400905990815, 6035.6076855004285, 6056.515478761733, 6066.817995460121, 5677.261955945701, 5743.885122297573, 5814.215080964101, 5819.592628516006, 5834.299884013945, 13050.673733156747, 13305.534306299232, 13316.134451260734, 15494.767578486331, 25143.37141571457, 2172.2180015194517, 2219.87790671513, 2221.1226565506536, 2863.1983044747503, 2932.6040984467036, 9536.189423898977, 9829.90170098297, 9918.40489570628, 10200.195870804817, 10430.79138417235, 1769.0535563473609, 1838.8495504813488, 1870.9803157279316, 1880.2760937039338, 1881.4731875075001, 104584.43123353156, 110960.4451977743, 122936.0, 126813.0, 127817.36091319576, 11026.062784663232, 11519.53921843117, 11599.55005624297, 11662.475188616532, 11884.762304753856, 11639.06887449002, 12896.35518224085, 14440.566782996528, 14510.709785804225, 14525.636859078397, 8102.837943241102, 8298.668053277923, 8301.8339633207, 8400.033996090406, 8441.83116337682, 8170.673173073129, 8223.671053157806, 8229.873352003544, 8347.747837824374, 8483.915160848435, 3644.854205831737, 3815.0, 4189.811458484375, 4395.472543294825, 4553.4991150973365, 1594.433975938525, 1608.3353728890777, 1874.250299880059, 1929.488685498344, 1954.6383918975034, 811.8619834628128, 855.8801767752519, 897.7217062710581, 982.1602529837029, 986.7335819328885, 20413.489662758253, 20565.89717051405, 20576.794232057488, 20696.48258793542, 20763.79236207649, 885.1237275097607, 909.2498688581946, 915.5651065743851, 925.9490728009889, 930.8696782450327, 10308.536115874804, 10959.671209863716, 10998.670039898816, 11005.944970275097, 11014.00873921351, 625590.0, 626279.0, 629583.0, 640967.0, 644116.0, 106563.46718266359, 115234.0, 119188.21217681741, 137031.0, 138099.0, 779.3336697123991, 820.8153165537706, 843.1273631791189, 963.8120566489467, 973.2992245583168, 18120.093995300445, 18725.063746812877, 18844.434666960013, 18877.15052822654, 18912.054397280357, 53728.0, 54158.72920635372, 54438.0, 54846.451535484135, 57379.71310143422, 71875.0, 75348.95812502535, 82825.58587207025, 83166.0, 83431.27848404912, 7605.467617266738, 7826.773542287101, 7938.722144724907, 7982.920170798217, 8115.83768324642, 11837.993770529602, 12250.571230006906, 12375.0, 12423.0, 12497.93751031239, 8404.789880252798, 10533.9473302633, 10552.947235263775, 10732.678019659423, 11035.841236670034, 9550.044995500395, 9882.30823842353, 10344.58663578831, 10460.63387781424, 10515.421651808918, 10759.946200268949, 10760.569577217137, 10940.781184376108, 10993.945030274797, 11151.219414640897, 7680.692772289046, 7903.1306556279915, 8000.039995200613, 8004.239597238253, 8017.318527925192, 9083.863742044006, 10568.10171135462, 10588.841167382341, 10695.625653102104, 10828.729281768019], "value": 9257.392412620788, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1383.5503461375054, 1388.9861101388985, 1618.9800425731787, 1652.8347165283474, 1667.8165401805807, 7486.438517111211, 7740.690372385112, 7967.760967170987, 8360.414770966036, 8441.113683063282, 7916.841663166733, 8215.91784082159, 8219.136990615974, 8329.0, 8337.958310208456, 2020.8582151084663, 2316.4324740438574, 2339.543788961153, 2344.1443872986406, 2350.4123969007746, 2309.7690230976937, 2325.093213646679, 2343.61330380487, 2368.0291080656934, 2375.5605213035556, 1975.8320542753884, 2320.431494283903, 2334.6264597664376, 2339.228054741931, 2343.2150229672993, 2296.6899468571696, 2304.3547806614174, 2305.8501197422115, 2321.4080409495555, 2334.813214942809, 2341.519988402373, 2372.988135059331, 2373.9762602373926, 2374.584447721642, 2374.78626923576, 8337.791555211108, 8393.916060839374, 8419.663213471455, 8461.66153353865, 8464.66141354345, 5053.46938571452, 5218.791248350061, 5220.060389129955, 5222.8694282642855, 5224.03355379256, 3295.3574053059424, 3428.297199074184, 3523.859045638171, 3595.4786555949504, 3596.8920932371807, 1026.343140390151, 1048.0661393253076, 1088.782243551285, 1093.0545078507114, 1107.6234080412619, 10840.837387439195, 10895.237333386665, 10943.945280273627, 10985.725356866062, 10995.175361847814, 4509.2559727644575, 4631.51369106239, 4702.835400761025, 4728.739919304402, 4750.242410308139, 9353.971063183044, 9359.62561497546, 9446.338756286994, 9446.811063778687, 9466.006069362755, 5170.224466330081, 5206.947930520722, 5208.114620514521, 5217.165253559412, 5217.8173763918085, 3623.873164439232, 4166.125243640314, 4726.503717109723, 4761.262004389325, 4787.640926930494, 3825.617438256152, 3866.6520013198947, 3894.7857867816974, 3940.3695408734466, 3945.3292940199663, 1271.713864380507, 1285.9103956071383, 1287.4785711786742, 1295.313483853559, 1299.4087690100982, 743.6802175064666, 867.3754896474528, 899.7120921305251, 919.2278486071598, 932.5523748600709, 5708.65748055118, 5845.620034697731, 5856.94143058564, 5880.705964701748, 6068.150458935753, 908.6183802802839, 970.3207754571687, 973.493783232715, 985.9112679858847, 987.9753006174759, 3256.2185075581688, 3283.7701360904985, 3775.528058992626, 3912.4131380292615, 3926.6466018058513, 9591.376560523544, 9923.801523969625, 10038.749031274276, 10227.02843229892, 10229.948850255701, 9888.456134912505, 9993.200543956465, 10171.4914254287, 10210.693679189637, 10226.53980570876, 7273.454490913097, 7573.962130189314, 7812.062552493623, 7822.6088695564995, 7852.685892564347, 5370.516653501204, 5807.128930660435, 5892.0, 5968.7612495501435, 5982.581219314607, 9309.953450232706, 9325.906740932774, 9433.905660943303, 9475.33672642936, 9506.61973521079, 9276.397034192756, 9499.525023748924, 9656.517174141125, 9678.032196780267, 9684.128428441474, 6606.372394622503, 6798.320167983162, 7026.578405295699, 7154.642267886481, 7284.453665975179, 7778.727744528915, 8018.118007019107, 8033.0, 8040.356771458386, 8056.355491560778, 7003.0, 7132.429405647639, 7173.282671732785, 7199.3520583147765, 7208.45936554771, 6808.080909077211, 6915.965420172867, 6988.685509152202, 7029.578225306498, 7063.117110361205, 6658.766943156967, 6686.966565167143, 6733.360330768569, 6740.797776066725, 6761.188657361148, 4913.975430122827, 4942.950570494393, 4944.110060189057, 4975.402951645826, 5004.899902002013, 4926.137925863031, 4939.333190019302, 4978.5270399312, 5005.7997680092385, 5012.04771093491], "value": 4439.613552815952, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [257.91617724239615, 297.7201430655184, 303.3039175093161, 310.5574556257333, 311.85498743084463, 5208.921866172011, 5324.387695415028, 5357.678539287645, 5503.229547863298, 5521.447855214482, 4577.015941572561, 5086.821961231358, 5227.0, 5339.305890234265, 5370.140777475604, 3033.135556366436, 3566.286742651467, 4001.7994601619544, 4004.939925901114, 4140.441040459529, 3436.329915666446, 3456.7580269381156, 3502.702270307027, 4017.27689015977, 4142.440770495975, 3743.5694895087004, 3991.1618560102415, 4054.108096218827, 4065.0650350419423, 4075.531313898895, 3992.2614316351423, 4010.057636455431, 4015.136745599681, 4027.114034912344, 4054.4729185205883, 2921.0, 3498.177928186874, 3501.159721666807, 3546.007118006962, 3566.288181672786, 4274.914501709979, 4888.87334009707, 4995.800167993276, 5201.55786758126, 5220.504052115043, 4205.11692544563, 4565.748883811389, 4612.746298953556, 4652.767361631939, 4674.368960190399, 4747.774480712169, 4805.831795887162, 4857.368542089541, 5077.923831142536, 5323.361196656388, 3150.8109513429267, 3525.594556625995, 3763.190913953486, 3836.7530552570524, 3845.1540661054523, 5140.712382989571, 5549.61473501164, 5903.834145123017, 5914.704264786744, 5922.58541902067, 3736.3933508591567, 3882.6311500407414, 3890.8327501749927, 3929.25344184604, 3952.5691699604536, 3264.665763978719, 3311.7019468247972, 3462.7056700180265, 3985.5217373915316, 3992.6606238469476, 3844.557875844314, 4210.642095421924, 4436.401085853368, 4578.101431468297, 4643.373144625432, 4441.645927658346, 4667.8330417395655, 5732.6847023413275, 5749.189005463266, 5778.711064446761, 1139.629620373382, 1226.8711785262597, 1258.9622311330675, 1273.936303184837, 1279.643577807527, 834.0533494483805, 843.8902942617543, 870.8998465176459, 873.5632183908046, 876.2770714160715, 3966.353963105279, 4783.851875549843, 4988.226824842155, 4996.275540046673, 5111.361079865017, 4870.95129048705, 4879.926801097987, 5378.91931621026, 5435.347758269033, 5521.840413513127, 4520.932186017147, 4695.483496815347, 4750.883542367541, 5070.873228169326, 5108.343661296105, 2551.9489610207693, 2860.7139286071642, 2874.3676391193903, 2900.303927057533, 2905.6513218413925, 4898.706077635282, 5605.411431799603, 5699.373068962411, 5727.339071669253, 5737.627054241461, 4409.669274804402, 4736.526347365236, 5536.86157846049, 5570.749316280777, 5696.401877802854, 4589.632829373642, 5179.689218646893, 5240.737963101829, 5386.78797270611, 5607.271054762937, 5418.783248670009, 5443.162570387946, 5459.808906688247, 5502.614816962774, 5540.202197890201, 4559.4756602989755, 4831.748061705817, 5391.730413479232, 5424.748729277306, 5476.892330751936, 5503.7798488061635, 5585.609007369445, 5669.914951275817, 5712.42875712442, 5735.627184233012, 3172.0, 3186.203449137716, 3364.848581813775, 3737.7570457920147, 3744.7378683491893, 5124.666896651705, 5226.921596175985, 5282.735863206901, 5343.385510666168, 5387.838364849061, 5381.946180538301, 5423.810166644149, 5507.678157242313, 5620.578456615688, 5664.745086471036, 4923.483034281492, 5607.691576963306, 5647.390493709273, 5674.233978412862, 5686.488216060575, 4880.341153944172, 5231.953609278084, 5570.58220633446, 5577.832665020056, 5709.914351284817, 5135.922961155511, 5611.803586874603, 5668.971655141863, 5696.772129114789, 5739.5695322850115, 4342.240107981028, 4405.625521830681, 4946.62900282473, 5182.170852663556, 5494.780208791603], "value": 3954.6838341827297, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.4831770518498, 602.0848310567934, 699.2064007351654, 711.7081996381484, 742.2095468326232, 13969.371378287977, 14778.704425911475, 14798.852011479881, 14867.851321486796, 14891.851081489169, 11778.175527713063, 13324.866751332484, 13510.392032358543, 13518.59444216675, 13552.593422197326, 635.9173307470046, 669.6283562622742, 695.7634404302523, 793.7936136604467, 824.3981893217924, 813.3168138763438, 821.4044817507289, 828.9253967142956, 830.3896635972555, 832.7335252719132, 861.7888617288774, 879.0813599788237, 880.9559522023906, 882.9470231786081, 885.4996926736405, 878.5343767803074, 880.8502554565739, 880.8898887639045, 882.1972005475051, 883.7878909061811, 651.6155468273741, 656.9704363303663, 656.9868602627969, 659.0410952064734, 661.5699795133154, 13569.864301356958, 13580.38888250031, 13784.931075344659, 13803.309834508334, 14409.927950360287, 13651.249181295025, 13681.247531385869, 13891.02762806604, 13931.874622122526, 14034.36845341962, 11087.057600104, 11112.499937502831, 11371.204015718902, 11413.0, 11422.543098276059, 3585.4801053847045, 3609.5488063992, 3651.5253017107607, 3677.1726361568435, 3677.9080522987138, 94155.0, 95369.56945645821, 96251.51874240584, 97703.99999999929, 98618.0, 10516.526756295985, 10781.299215550964, 10801.83797243042, 10886.836697449544, 10993.835092473619, 20976.790232097483, 21109.78890211109, 21177.364679059654, 21181.36455906325, 21561.460963475725, 7613.276738709925, 7613.695452181962, 7616.200298968529, 7699.050692804993, 7770.378369730408, 3553.4314509678843, 4138.151678905817, 4156.646685031746, 4242.363645453207, 4243.978780106141, 3271.820049897231, 3376.189714468559, 3390.7287417006582, 3393.253484233465, 3398.847051882671, 619.2321521313629, 625.7747211004034, 638.1257676982564, 647.873664635401, 659.9439047680905, 492.4410793749121, 563.8703098287417, 575.4446958684889, 587.8198943714484, 597.9312379076375, 7906.920930790733, 8046.0, 8554.43141234177, 8699.782505437415, 8718.128187181232, 546.6446809573729, 576.9105788602774, 582.659144400522, 584.8245526342081, 607.2166904692984, 3924.6860251179837, 4197.454330936958, 4241.024564350217, 4314.115606300701, 4347.0, 109725.35411968827, 113812.0, 116022.83977160288, 117836.8216317843, 118482.40758796323, 96626.0, 97283.51358243164, 98778.50610747044, 100060.0, 100677.99322006833, 89152.0, 89479.55260223788, 91625.0, 92615.61076583857, 92936.0, 6899.689513971984, 8232.794180145544, 8298.58507074656, 8303.875441868498, 8489.193526614961, 52330.903542974054, 52802.9439411202, 58132.0, 58811.70594147002, 59933.101003485855, 62227.0, 66749.99875001976, 70397.9440308406, 78373.0, 82189.0, 10178.338408003752, 11443.485043173243, 11506.94246528762, 12508.437120329425, 12658.050646201757, 13707.0, 13866.584002479942, 14322.641933951734, 14650.120992740469, 15014.47449339268, 12581.74836503283, 12741.55404560836, 13359.933200333937, 13478.326083695973, 13608.387622557208, 11774.94112529432, 11803.468843902217, 12609.243445393306, 13556.525521606696, 13924.651883702583, 12530.498780048696, 13018.0, 13192.604221873358, 13504.662383440493, 13542.255175965416, 9982.700518984442, 10277.84583231267, 10549.0, 10969.0, 11714.589989350332, 11203.327800331681, 11371.886281137413, 11453.083753299883, 11459.08327333828, 11466.656000320003], "value": 7219.143049709405, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1568.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 117.0, 120.0, 118.0, 117.0, 150.0, 149.0, 118.0, 120.0], "value": 123.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 111.0, 145.0, 113.0, 115.0, 112.0, 113.0, 141.0, 113.0], "value": 122.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 199.0, 198.0, 205.0, 201.0, 194.0, 241.0, 256.0, 196.0, 204.0], "value": 209.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 61.0, 59.0, 89.0, 61.0, 60.0, 60.0, 61.0, 80.0, 62.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 86.0, 74.0, 70.0, 72.0, 85.0, 70.0, 69.0, 92.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 138.0, 135.0, 106.0, 86.0, 92.0, 87.0, 91.0, 89.0], "value": 104.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 137.0, 105.0, 156.0, 146.0, 154.0, 95.0, 97.0, 97.0, 95.0], "value": 117.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 92.0, 130.0, 144.0, 132.0, 110.0, 76.0, 124.0, 76.0], "value": 105.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 60.0, 61.0, 61.0, 60.0, 59.0, 57.0, 63.0, 61.0], "value": 59.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 68.0, 68.0, 109.0, 117.0, 108.0, 107.0, 107.0, 73.0, 73.0], "value": 90.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 82.0, 82.0, 93.0, 77.0, 94.0, 79.0, 84.0, 104.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 141.0, 141.0, 142.0, 141.0, 130.0, 138.0, 145.0, 189.0, 153.0], "value": 146.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 209.0, 221.0, 164.0, 220.0, 193.0, 169.0, 168.0, 166.0, 191.0], "value": 185.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 71.0, 74.0, 91.0, 87.0, 68.0, 70.0, 67.0, 69.0], "value": 73.3, "unit": "score"}]}]} 17:05:35 INFO - Return code: 0 17:05:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:05:35 ERROR - # TBPL SUCCESS # 17:05:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:05:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:05:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:05:35 INFO - 2017-01-12 17:05:35.423 screenresolution[1659:10608] starting screenresolution argv=screenresolution get 17:05:35 INFO - 2017-01-12 17:05:35.426 screenresolution[1659:10608] Display 0: 1600x1200x32@60 17:05:35 INFO - 2017-01-12 17:05:35.433 screenresolution[1660:10611] starting screenresolution argv=screenresolution list 17:05:35 INFO - Available Modes on Display 0 17:05:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:05:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:05:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:05:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:05:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:05:35 INFO - Intel Iris: 17:05:35 INFO - Chipset Model: Intel Iris 17:05:35 INFO - Type: GPU 17:05:35 INFO - Bus: Built-In 17:05:35 INFO - VRAM (Dynamic, Max): 1536 MB 17:05:35 INFO - Vendor: Intel (0x8086) 17:05:35 INFO - Device ID: 0x0a2e 17:05:35 INFO - Revision ID: 0x0009 17:05:35 INFO - Displays: 17:05:35 INFO - Display: 17:05:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:05:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:05:35 INFO - Display Serial Number: 1600x1200 60 17:05:35 INFO - Main Display: Yes 17:05:35 INFO - Mirror: Off 17:05:35 INFO - Online: Yes 17:05:35 INFO - Rotation: Supported 17:05:35 INFO - Return code: 0 17:05:35 INFO - Running post-action listener: _resource_record_post_action 17:05:35 INFO - [mozharness: 2017-01-13 01:05:35.678207Z] Finished run-tests step (success) 17:05:35 INFO - Running post-run listener: _resource_record_post_run 17:05:35 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 39700992; Write bytes: 660508672; Read time: 391; Write time: 1896 17:05:35 INFO - TinderboxPrint: CPU usage
54.3% 17:05:35 INFO - TinderboxPrint: I/O read bytes / time
39,700,992 / 391 17:05:35 INFO - TinderboxPrint: I/O write bytes / time
660,508,672 / 1,896 17:05:35 INFO - TinderboxPrint: CPU idle
2,210.5 (45.7%) 17:05:35 INFO - TinderboxPrint: CPU system
982.2 (20.3%) 17:05:35 INFO - TinderboxPrint: CPU user
1,649.0 (34.1%) 17:05:35 INFO - TinderboxPrint: Swap in / out
388,599,808 / 0 17:05:35 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 341074432; Write bytes: 335050240; Read time: 26926; Write time: 612 17:05:35 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 38722048; Write bytes: 308783616; Read time: 375; Write time: 1234 17:05:36 INFO - Running post-run listener: _upload_blobber_files 17:05:36 INFO - Blob upload gear active. 17:05:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:05:36 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 17:05:36 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'] 17:05:36 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 17:05:36 INFO - (blobuploader) - INFO - Open directory for files ... 17:05:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:05:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:05:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:37 INFO - (blobuploader) - INFO - Done attempting. 17:05:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:05:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:05:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:38 INFO - (blobuploader) - INFO - Done attempting. 17:05:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:05:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:05:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:42 INFO - (blobuploader) - INFO - Done attempting. 17:05:42 INFO - (blobuploader) - INFO - Iteration through files over. 17:05:42 INFO - Return code: 0 17:05:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:05:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:05:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/359b2e5e134f53edb4cda8ff473f8d7808816fa21306ef9b4df6762a93545cf0e45ebc8105838b3c6720df9f8679f13e726635ccbde792b5cd72a56614229502", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e84cbaa9975a19ebd5039f367464030d50a769841d61518e7bea4bd160b4e71b58beea5089b6be448f93c37c39e535e630b4f008d02f16eb5a6a7942e737f43f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3b6f19572969d591592ba2183a14c4c5237a9c0af2b76b4f39babf0881b6780a715decf0038ab93ac43281f10af4809a124f8963f02662b49002bfa076f53c7"} 17:05:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:05:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:05:42 INFO - Contents: 17:05:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/359b2e5e134f53edb4cda8ff473f8d7808816fa21306ef9b4df6762a93545cf0e45ebc8105838b3c6720df9f8679f13e726635ccbde792b5cd72a56614229502", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e84cbaa9975a19ebd5039f367464030d50a769841d61518e7bea4bd160b4e71b58beea5089b6be448f93c37c39e535e630b4f008d02f16eb5a6a7942e737f43f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3b6f19572969d591592ba2183a14c4c5237a9c0af2b76b4f39babf0881b6780a715decf0038ab93ac43281f10af4809a124f8963f02662b49002bfa076f53c7"} 17:05:42 INFO - Running post-run listener: copy_logs_to_upload_dir 17:05:42 INFO - Copying logs to upload dir... 17:05:42 INFO - mkdir: /builds/slave/test/build/upload/logs 17:05:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.739224 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-12 17:05:42.126154) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 17:05:42.130961) ========= 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.0NyIJxubzp/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.eTbyJTO8O7/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/359b2e5e134f53edb4cda8ff473f8d7808816fa21306ef9b4df6762a93545cf0e45ebc8105838b3c6720df9f8679f13e726635ccbde792b5cd72a56614229502", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e84cbaa9975a19ebd5039f367464030d50a769841d61518e7bea4bd160b4e71b58beea5089b6be448f93c37c39e535e630b4f008d02f16eb5a6a7942e737f43f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3b6f19572969d591592ba2183a14c4c5237a9c0af2b76b4f39babf0881b6780a715decf0038ab93ac43281f10af4809a124f8963f02662b49002bfa076f53c7"} build_url:https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011462 build_url: 'https://queue.taskcluster.net/v1/task/CAO93FkHRCKmL-e1pW4GHA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/359b2e5e134f53edb4cda8ff473f8d7808816fa21306ef9b4df6762a93545cf0e45ebc8105838b3c6720df9f8679f13e726635ccbde792b5cd72a56614229502", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e84cbaa9975a19ebd5039f367464030d50a769841d61518e7bea4bd160b4e71b58beea5089b6be448f93c37c39e535e630b4f008d02f16eb5a6a7942e737f43f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3b6f19572969d591592ba2183a14c4c5237a9c0af2b76b4f39babf0881b6780a715decf0038ab93ac43281f10af4809a124f8963f02662b49002bfa076f53c7"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 17:05:42.169062) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 17:05:42.169435) ========= 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.0NyIJxubzp/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.eTbyJTO8O7/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.005144 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 17:05:42.218510) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 17:05:42.218866) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 17:05:42.219217) ========= ========= Total master_lag: 0.21 =========