builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0243 starttime: 1479833867.65 results: success (0) buildid: 20161122062251 builduid: 7d157221a41944fea2ef5b6b34eff9e5 revision: 8be0fc0d33ac7e844dd011fd1f487545eab0d79c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 08:57:47.654764) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 08:57:47.655179) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 08:57:47.655454) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 08:57:47.682882) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 08:57:47.683170) ========= 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.RWI4iO7dKz/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.DfZIxxJtov/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-22 08:57:47-- 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.2M=0.001s 2016-11-22 08:57:47 (19.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.076991 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 08:57:47.783165) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 08:57:47.783481) ========= 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.RWI4iO7dKz/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.DfZIxxJtov/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.021350 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 08:57:47.832286) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 08:57:47.832632) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8be0fc0d33ac7e844dd011fd1f487545eab0d79c --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 8be0fc0d33ac7e844dd011fd1f487545eab0d79c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RWI4iO7dKz/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.DfZIxxJtov/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-22 08:57:47,919 truncating revision to first 12 chars 2016-11-22 08:57:47,919 Setting DEBUG logging. 2016-11-22 08:57:47,920 attempt 1/10 2016-11-22 08:57:47,920 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8be0fc0d33ac?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 08:57:48,208 unpacking tar archive at: mozilla-aurora-8be0fc0d33ac/testing/mozharness/ program finished with exit code 0 elapsedTime=0.551755 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 08:57:48.407848) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 08:57:48.408176) ========= script_repo_revision: 8be0fc0d33ac7e844dd011fd1f487545eab0d79c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 08:57:48.408576) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 08:57:48.408861) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 08:57:48.429158) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-22 08:57:48.429964) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RWI4iO7dKz/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.DfZIxxJtov/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:57:48 INFO - MultiFileLogger online at 20161122 08:57:48 in /builds/slave/test 08:57:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:57:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:57:48 INFO - {'append_to_log': False, 08:57:48 INFO - 'base_work_dir': '/builds/slave/test', 08:57:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:57:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:57:48 INFO - 'branch': 'Mozilla-Aurora', 08:57:48 INFO - 'buildbot_json_path': 'buildprops.json', 08:57:48 INFO - 'config_files': ('talos/mac_config.py',), 08:57:48 INFO - 'default_actions': ('clobber', 08:57:48 INFO - 'read-buildbot-config', 08:57:48 INFO - 'download-and-extract', 08:57:48 INFO - 'populate-webroot', 08:57:48 INFO - 'create-virtualenv', 08:57:48 INFO - 'install', 08:57:48 INFO - 'run-tests'), 08:57:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:57:48 INFO - 'download_minidump_stackwalk': True, 08:57:48 INFO - 'download_symbols': 'ondemand', 08:57:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:57:48 INFO - 'tooltool.py': '/tools/tooltool.py', 08:57:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:57:48 INFO - '/tools/misc-python/virtualenv.py')}, 08:57:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:57:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:57:48 INFO - 'installer_path': 'installer.exe', 08:57:48 INFO - 'log_level': 'info', 08:57:48 INFO - 'log_name': 'talos', 08:57:48 INFO - 'log_to_console': True, 08:57:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:57:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:57:48 INFO - 'opt_config_files': (), 08:57:48 INFO - 'pip_index': False, 08:57:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:57:48 INFO - 'cmd': ('bash', 08:57:48 INFO - '-c', 08:57:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:57:48 INFO - 'enabled': True, 08:57:48 INFO - 'halt_on_failure': False, 08:57:48 INFO - 'name': 'check_screen_resolution'},), 08:57:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:57:48 INFO - 'cmd': ('bash', 08:57:48 INFO - '-c', 08:57:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:57:48 INFO - 'enabled': True, 08:57:48 INFO - 'halt_on_failure': False, 08:57:48 INFO - 'name': 'check_screen_resolution'},), 08:57:48 INFO - 'run_cmd_checks_enabled': True, 08:57:48 INFO - 'sps_profile': False, 08:57:48 INFO - 'sps_profile_interval': 0, 08:57:48 INFO - 'suite': 'dromaeojs', 08:57:48 INFO - 'system_bits': '32', 08:57:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:57:48 INFO - 'title': 't-yosemite-r7-0243', 08:57:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:57:48 INFO - 'use_talos_json': True, 08:57:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:57:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:57:48 INFO - 'work_dir': 'build'} 08:57:48 INFO - [mozharness: 2016-11-22 16:57:48.635796Z] Running clobber step. 08:57:48 INFO - Running pre-action listener: _resource_record_pre_action 08:57:48 INFO - Running main action method: clobber 08:57:48 INFO - rmtree: /builds/slave/test/build 08:57:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:57:49 INFO - Running post-action listener: _resource_record_post_action 08:57:49 INFO - [mozharness: 2016-11-22 16:57:49.631880Z] Finished clobber step (success) 08:57:49 INFO - [mozharness: 2016-11-22 16:57:49.632022Z] Running read-buildbot-config step. 08:57:49 INFO - Running pre-action listener: _resource_record_pre_action 08:57:49 INFO - Running main action method: read_buildbot_config 08:57:49 INFO - Using buildbot properties: 08:57:49 INFO - { 08:57:49 INFO - "project": "", 08:57:49 INFO - "product": "firefox", 08:57:49 INFO - "script_repo_revision": "production", 08:57:49 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:57:49 INFO - "repository": "", 08:57:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:57:49 INFO - "buildid": "20161122062251", 08:57:49 INFO - "pgo_build": "False", 08:57:49 INFO - "basedir": "/builds/slave/test", 08:57:49 INFO - "buildnumber": 45, 08:57:49 INFO - "slavename": "t-yosemite-r7-0243", 08:57:49 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:57:49 INFO - "platform": "yosemite_r7", 08:57:49 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:57:49 INFO - "branch": "mozilla-aurora", 08:57:49 INFO - "repo_path": "releases/mozilla-aurora", 08:57:49 INFO - "revision": "8be0fc0d33ac7e844dd011fd1f487545eab0d79c", 08:57:49 INFO - "stage_platform": "macosx64", 08:57:49 INFO - "builduid": "7d157221a41944fea2ef5b6b34eff9e5", 08:57:49 INFO - "slavebuilddir": "test" 08:57:49 INFO - } 08:57:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:57:49 INFO - Running post-action listener: _resource_record_post_action 08:57:49 INFO - [mozharness: 2016-11-22 16:57:49.634952Z] Finished read-buildbot-config step (success) 08:57:49 INFO - [mozharness: 2016-11-22 16:57:49.635057Z] Running download-and-extract step. 08:57:49 INFO - Running pre-action listener: _resource_record_pre_action 08:57:49 INFO - Running main action method: download_and_extract 08:57:49 INFO - mkdir: /builds/slave/test/build/tests 08:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:49 INFO - trying https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:57:49 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:57:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:57:50 INFO - Downloaded 1199 bytes. 08:57:50 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:57:50 INFO - Using the following test package requirements: 08:57:50 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:57:50 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:57:50 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:57:50 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:57:50 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:57:50 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:57:50 INFO - u'jsshell-mac.zip'], 08:57:50 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:57:50 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:57:50 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:57:50 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:57:50 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:57:50 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:57:50 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:57:50 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:57:50 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:57:50 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:57:50 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:57:50 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:57:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:57:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:57:50 INFO - Fetch https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:57:51 INFO - Http code: 200 08:57:51 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 08:57:51 INFO - x-amz-cf-id: 6yTEbe1c5TxBBYiFOSzCqacuFsf9zPZBhNZqo1TpvP5j1k5DLiKSHg== 08:57:51 INFO - x-amz-version-id: yZgh7R_2YnqHVOUqMZtKSyigm2xD7YIC 08:57:51 INFO - Expected file size: 16277209 08:57:51 INFO - Obtained file size: 16277209 08:57:58 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:57:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:57:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:57:58 INFO - Fetch https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:57:58 INFO - Http code: 200 08:57:58 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 08:57:58 INFO - x-amz-cf-id: JQb-hUJbAznS7BcuiHbRar4WNfp8dszMWZ2DFE5BLtfLguYKlfZNTw== 08:57:58 INFO - x-amz-version-id: yZgh7R_2YnqHVOUqMZtKSyigm2xD7YIC 08:57:58 INFO - Expected file size: 16277209 08:57:58 INFO - Obtained file size: 16277209 08:58:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:58:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:58:05 INFO - Fetch https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:58:06 INFO - Http code: 200 08:58:06 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 08:58:06 INFO - x-amz-cf-id: WPHuSy4M5mdVI9exnZ5liWJzq0M68fdagrv-t3uDBOOY_b2NXnm59w== 08:58:06 INFO - x-amz-version-id: KHK7N4Ph547oakxC3Ctila7yc9NQnpD8 08:58:06 INFO - Expected file size: 14409038 08:58:06 INFO - Obtained file size: 14409038 08:58: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')]} 08:58:07 INFO - trying https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:58:07 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:58:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:58:10 INFO - Downloaded 114501264 bytes. 08:58:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:58:10 INFO - mkdir: /builds/slave/test/properties 08:58:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:58:10 INFO - Writing to file /builds/slave/test/properties/build_url 08:58:10 INFO - Contents: 08:58:10 INFO - build_url:https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:58:10 INFO - Running post-action listener: _resource_record_post_action 08:58:10 INFO - Running post-action listener: set_extra_try_arguments 08:58:10 INFO - [mozharness: 2016-11-22 16:58:10.741865Z] Finished download-and-extract step (success) 08:58:10 INFO - [mozharness: 2016-11-22 16:58:10.742009Z] Running populate-webroot step. 08:58:10 INFO - Running pre-action listener: _resource_record_pre_action 08:58:10 INFO - Running main action method: populate_webroot 08:58:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:58:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:58:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:58:10 INFO - '--tppagecycles', 08:58:10 INFO - '7'], 08:58:10 INFO - 'tests': ['tsvgm']}}, 08:58:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:58:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:58:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:58:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:58:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:58:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:58:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:10 INFO - 'talos_options': ['--disable-e10s'], 08:58:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:58:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:58:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:10 INFO - 'talos_options': ['--disable-e10s'], 08:58:10 INFO - 'tests': ['damp', 'tps']}, 08:58:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:10 INFO - 'tests': ['damp', 'tps']}, 08:58:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:58:10 INFO - 'tests': ['dromaeo_dom']}, 08:58:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:58:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:58:10 INFO - 'tests': ['basic_compositor_video']}, 08:58:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:58:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:58:10 INFO - 'tests': ['a11yr', 08:58:10 INFO - 'ts_paint', 08:58:10 INFO - 'tpaint', 08:58:10 INFO - 'sessionrestore', 08:58:10 INFO - 'sessionrestore_no_auto_restore', 08:58:10 INFO - 'tabpaint']}, 08:58:10 INFO - 'other-e10s': {'tests': ['a11yr', 08:58:10 INFO - 'ts_paint', 08:58:10 INFO - 'tpaint', 08:58:10 INFO - 'sessionrestore', 08:58:10 INFO - 'sessionrestore_no_auto_restore', 08:58:10 INFO - 'tabpaint']}, 08:58:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:58:10 INFO - 'ts_paint', 08:58:10 INFO - 'tpaint', 08:58:10 INFO - 'sessionrestore', 08:58:10 INFO - 'sessionrestore_no_auto_restore', 08:58:10 INFO - 'tabpaint']}, 08:58:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:58:10 INFO - 'ts_paint', 08:58:10 INFO - 'tpaint', 08:58:10 INFO - 'sessionrestore', 08:58:10 INFO - 'sessionrestore_no_auto_restore', 08:58:10 INFO - 'tabpaint']}, 08:58:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:58:10 INFO - 'tests': ['a11yr', 08:58:10 INFO - 'ts_paint', 08:58:10 INFO - 'tpaint', 08:58:10 INFO - 'sessionrestore', 08:58:10 INFO - 'sessionrestore_no_auto_restore', 08:58:10 INFO - 'tabpaint']}, 08:58:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:58:10 INFO - 'tests': ['a11yr', 08:58:10 INFO - 'ts_paint', 08:58:10 INFO - 'tpaint', 08:58:10 INFO - 'sessionrestore', 08:58:10 INFO - 'sessionrestore_no_auto_restore', 08:58:10 INFO - 'tabpaint']}, 08:58:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:58:10 INFO - 'tests': ['tsvgx', 08:58:10 INFO - 'tsvgr_opacity', 08:58:10 INFO - 'tart', 08:58:10 INFO - 'tscrollx', 08:58:10 INFO - 'cart']}, 08:58:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:58:10 INFO - 'tsvgr_opacity', 08:58:10 INFO - 'tart', 08:58:10 INFO - 'tscrollx', 08:58:10 INFO - 'cart']}, 08:58:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:10 INFO - 'talos_options': ['--disable-e10s'], 08:58:10 INFO - 'tests': ['tp5o']}, 08:58:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:10 INFO - 'tests': ['tp5o']}, 08:58:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:10 INFO - 'talos_options': ['--disable-e10s', 08:58:10 INFO - '--xperf_path', 08:58:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:58:10 INFO - 'tests': ['tp5n']}, 08:58:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:10 INFO - 'talos_options': ['--xperf_path', 08:58:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:58:10 INFO - 'tests': ['tp5n']}}, 08:58:10 INFO - 'talos.zip': {'path': '', 08:58:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:58:10 INFO - Running post-action listener: _resource_record_post_action 08:58:10 INFO - [mozharness: 2016-11-22 16:58:10.760490Z] Finished populate-webroot step (success) 08:58:10 INFO - [mozharness: 2016-11-22 16:58:10.760617Z] Running create-virtualenv step. 08:58:10 INFO - Running pre-action listener: _resource_record_pre_action 08:58:10 INFO - Running main action method: create_virtualenv 08:58:10 INFO - Creating virtualenv /builds/slave/test/build/venv 08:58:10 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:58:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:58:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:58:10 INFO - Using real prefix '/tools/python27' 08:58:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:58:11 INFO - Installing distribute.............................................................................................................................................................................................done. 08:58:14 INFO - Installing pip.................done. 08:58:14 INFO - Return code: 0 08:58:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:58:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:58:14 INFO - Reading from file tmpfile_stdout 08:58:14 INFO - Output received: 08:58:14 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:58:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:58:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf4f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f85aa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd63d30>, '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 0x10cbc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5bb20>, '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.RWI4iO7dKz/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.DfZIxxJtov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:58:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:58:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:58:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWI4iO7dKz/Render', 08:58:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:14 INFO - 'HOME': '/Users/cltbld', 08:58:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:14 INFO - 'LOGNAME': 'cltbld', 08:58:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:14 INFO - 'MOZ_NO_REMOTE': '1', 08:58:14 INFO - 'NO_EM_RESTART': '1', 08:58:14 INFO - 'PAGER': '/bin/cat', 08:58:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:14 INFO - 'PWD': '/builds/slave/test', 08:58:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:14 INFO - 'SHELL': '/bin/bash', 08:58:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfZIxxJtov/Listeners', 08:58:14 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 08:58:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:14 INFO - 'USER': 'cltbld', 08:58:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:14 INFO - 'XPC_FLAGS': '0x0', 08:58:14 INFO - 'XPC_SERVICE_NAME': '0', 08:58:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:58:14 INFO - Cleaning up... 08:58:14 INFO - Return code: 0 08:58:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:58:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf4f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f85aa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd63d30>, '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 0x10cbc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5bb20>, '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.RWI4iO7dKz/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.DfZIxxJtov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:58:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:58:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:58:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWI4iO7dKz/Render', 08:58:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:15 INFO - 'HOME': '/Users/cltbld', 08:58:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:15 INFO - 'LOGNAME': 'cltbld', 08:58:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:15 INFO - 'MOZ_NO_REMOTE': '1', 08:58:15 INFO - 'NO_EM_RESTART': '1', 08:58:15 INFO - 'PAGER': '/bin/cat', 08:58:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:15 INFO - 'PWD': '/builds/slave/test', 08:58:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:15 INFO - 'SHELL': '/bin/bash', 08:58:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfZIxxJtov/Listeners', 08:58:15 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 08:58:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:15 INFO - 'USER': 'cltbld', 08:58:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:15 INFO - 'XPC_FLAGS': '0x0', 08:58:15 INFO - 'XPC_SERVICE_NAME': '0', 08:58:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:15 INFO - Downloading/unpacking psutil>=3.1.1 08:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:58:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:58:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:58:18 INFO - Installing collected packages: psutil 08:58:18 INFO - Running setup.py install for psutil 08:58:18 INFO - building 'psutil._psutil_osx' extension 08:58:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:58:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:58:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:58:19 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:58:19 INFO - building 'psutil._psutil_posix' extension 08:58:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:58:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:58:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:58:19 INFO - ^ 08:58:19 INFO - 1 warning generated. 08:58:19 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:58:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:58:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:58:19 INFO - Successfully installed psutil 08:58:19 INFO - Cleaning up... 08:58:19 INFO - Return code: 0 08:58:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf4f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f85aa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd63d30>, '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 0x10cbc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5bb20>, '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.RWI4iO7dKz/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.DfZIxxJtov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:58:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:58:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:58:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWI4iO7dKz/Render', 08:58:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:19 INFO - 'HOME': '/Users/cltbld', 08:58:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:19 INFO - 'LOGNAME': 'cltbld', 08:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:19 INFO - 'MOZ_NO_REMOTE': '1', 08:58:19 INFO - 'NO_EM_RESTART': '1', 08:58:19 INFO - 'PAGER': '/bin/cat', 08:58:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:19 INFO - 'PWD': '/builds/slave/test', 08:58:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:19 INFO - 'SHELL': '/bin/bash', 08:58:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfZIxxJtov/Listeners', 08:58:19 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 08:58:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:19 INFO - 'USER': 'cltbld', 08:58:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:19 INFO - 'XPC_FLAGS': '0x0', 08:58:19 INFO - 'XPC_SERVICE_NAME': '0', 08:58:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:58: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 08:58: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 08:58: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 08:58: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 08:58: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 08:58: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 08:58:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:58:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:58:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:58:22 INFO - Installing collected packages: mozsystemmonitor 08:58:22 INFO - Running setup.py install for mozsystemmonitor 08:58:22 INFO - Successfully installed mozsystemmonitor 08:58:22 INFO - Cleaning up... 08:58:22 INFO - Return code: 0 08:58:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf4f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f85aa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd63d30>, '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 0x10cbc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5bb20>, '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.RWI4iO7dKz/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.DfZIxxJtov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:58:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:58:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWI4iO7dKz/Render', 08:58:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:22 INFO - 'HOME': '/Users/cltbld', 08:58:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:22 INFO - 'LOGNAME': 'cltbld', 08:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:22 INFO - 'MOZ_NO_REMOTE': '1', 08:58:22 INFO - 'NO_EM_RESTART': '1', 08:58:22 INFO - 'PAGER': '/bin/cat', 08:58:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:22 INFO - 'PWD': '/builds/slave/test', 08:58:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:22 INFO - 'SHELL': '/bin/bash', 08:58:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfZIxxJtov/Listeners', 08:58:22 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 08:58:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:22 INFO - 'USER': 'cltbld', 08:58:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:22 INFO - 'XPC_FLAGS': '0x0', 08:58:22 INFO - 'XPC_SERVICE_NAME': '0', 08:58:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:23 INFO - Downloading/unpacking jsonschema==2.5.1 08:58:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:58:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:25 INFO - Downloading functools32-3.2.3-2.tar.gz 08:58:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:58:25 INFO - warning: no files found matching '*.txt' 08:58:25 INFO - no previously-included directories found matching 'build' 08:58:25 INFO - no previously-included directories found matching 'dist' 08:58:25 INFO - no previously-included directories found matching '.git*' 08:58:26 INFO - Installing collected packages: jsonschema, functools32 08:58:26 INFO - Running setup.py install for functools32 08:58:26 INFO - warning: no files found matching '*.txt' 08:58:26 INFO - no previously-included directories found matching 'build' 08:58:26 INFO - no previously-included directories found matching 'dist' 08:58:26 INFO - no previously-included directories found matching '.git*' 08:58:26 INFO - Successfully installed jsonschema functools32 08:58:26 INFO - Cleaning up... 08:58:26 INFO - Return code: 0 08:58:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:26 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 0x10cf4f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f85aa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd63d30>, '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 0x10cbc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5bb20>, '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.RWI4iO7dKz/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.DfZIxxJtov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:58:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:58:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:58:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWI4iO7dKz/Render', 08:58:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:26 INFO - 'HOME': '/Users/cltbld', 08:58:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:26 INFO - 'LOGNAME': 'cltbld', 08:58:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:26 INFO - 'MOZ_NO_REMOTE': '1', 08:58:26 INFO - 'NO_EM_RESTART': '1', 08:58:26 INFO - 'PAGER': '/bin/cat', 08:58:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:26 INFO - 'PWD': '/builds/slave/test', 08:58:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:26 INFO - 'SHELL': '/bin/bash', 08:58:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfZIxxJtov/Listeners', 08:58:26 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 08:58:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:26 INFO - 'USER': 'cltbld', 08:58:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:26 INFO - 'XPC_FLAGS': '0x0', 08:58:26 INFO - 'XPC_SERVICE_NAME': '0', 08:58:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:58:26 INFO - Cleaning up... 08:58:26 INFO - Return code: 0 08:58:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:26 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 0x10cf4f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f85aa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd63d30>, '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 0x10cbc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5bb20>, '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.RWI4iO7dKz/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.DfZIxxJtov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:58:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:58:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:58:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWI4iO7dKz/Render', 08:58:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:26 INFO - 'HOME': '/Users/cltbld', 08:58:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:26 INFO - 'LOGNAME': 'cltbld', 08:58:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:26 INFO - 'MOZ_NO_REMOTE': '1', 08:58:26 INFO - 'NO_EM_RESTART': '1', 08:58:26 INFO - 'PAGER': '/bin/cat', 08:58:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:26 INFO - 'PWD': '/builds/slave/test', 08:58:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:26 INFO - 'SHELL': '/bin/bash', 08:58:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfZIxxJtov/Listeners', 08:58:26 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 08:58:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:26 INFO - 'USER': 'cltbld', 08:58:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:26 INFO - 'XPC_FLAGS': '0x0', 08:58:26 INFO - 'XPC_SERVICE_NAME': '0', 08:58:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:26 INFO - Downloading/unpacking blobuploader==1.2.4 08:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:29 INFO - Downloading blobuploader-1.2.4.tar.gz 08:58:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:58:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:58:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:30 INFO - Downloading docopt-0.6.1.tar.gz 08:58:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:58:30 INFO - Installing collected packages: blobuploader, requests, docopt 08:58:30 INFO - Running setup.py install for blobuploader 08:58:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:58:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:58:30 INFO - Running setup.py install for requests 08:58:31 INFO - Running setup.py install for docopt 08:58:31 INFO - Successfully installed blobuploader requests docopt 08:58:31 INFO - Cleaning up... 08:58:31 INFO - Return code: 0 08:58:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:58:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:31 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 0x10cf4f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f85aa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd63d30>, '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 0x10cbc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5bb20>, '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.RWI4iO7dKz/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.DfZIxxJtov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:58:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:58:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:58:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWI4iO7dKz/Render', 08:58:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:31 INFO - 'HOME': '/Users/cltbld', 08:58:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:31 INFO - 'LOGNAME': 'cltbld', 08:58:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:31 INFO - 'MOZ_NO_REMOTE': '1', 08:58:31 INFO - 'NO_EM_RESTART': '1', 08:58:31 INFO - 'PAGER': '/bin/cat', 08:58:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:31 INFO - 'PWD': '/builds/slave/test', 08:58:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:31 INFO - 'SHELL': '/bin/bash', 08:58:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfZIxxJtov/Listeners', 08:58:31 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 08:58:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:31 INFO - 'USER': 'cltbld', 08:58:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:31 INFO - 'XPC_FLAGS': '0x0', 08:58:31 INFO - 'XPC_SERVICE_NAME': '0', 08:58:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:58:31 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-4Q3LSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:58:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:58:31 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-5JxDPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:58:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:58:31 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-AZP90e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:58:32 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-FkRTRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:58:32 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-ja9mBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:58:32 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-b3YvrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:58:32 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Grb1OG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:58:32 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-hIGgyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:58:32 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-tktYDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:58:32 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-TkziLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:58:32 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-pkqToa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:58:32 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-X5lMA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:58:33 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-bA4DlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:58:33 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-cE8PAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:58:33 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-SMLEW_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:58:33 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-qKOeK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:58:33 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-5SI0Ra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:58:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:58:33 INFO - Running setup.py install for manifestparser 08:58:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:58:33 INFO - Running setup.py install for mozcrash 08:58:33 INFO - Running setup.py install for mozdebug 08:58:33 INFO - Running setup.py install for mozdevice 08:58:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:58:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:58:34 INFO - Running setup.py install for mozfile 08:58:34 INFO - Running setup.py install for mozhttpd 08:58:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:58:34 INFO - Running setup.py install for mozinfo 08:58:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:58:34 INFO - Running setup.py install for mozInstall 08:58:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:58:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:58:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:58:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:58:34 INFO - Running setup.py install for mozleak 08:58:34 INFO - Running setup.py install for mozlog 08:58:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:58:35 INFO - Running setup.py install for moznetwork 08:58:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:58:35 INFO - Running setup.py install for mozprocess 08:58:35 INFO - Running setup.py install for mozprofile 08:58:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:58:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:58:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:58:35 INFO - Running setup.py install for mozrunner 08:58:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:58:35 INFO - Running setup.py install for mozscreenshot 08:58:35 INFO - Running setup.py install for moztest 08:58:35 INFO - Running setup.py install for mozversion 08:58:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:58:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:58:36 INFO - Cleaning up... 08:58:36 INFO - Return code: 0 08:58:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:58:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:36 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 0x10cf4f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f85aa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd63d30>, '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 0x10cbc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5bb20>, '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.RWI4iO7dKz/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.DfZIxxJtov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:58:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:58:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:58:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWI4iO7dKz/Render', 08:58:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:36 INFO - 'HOME': '/Users/cltbld', 08:58:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:36 INFO - 'LOGNAME': 'cltbld', 08:58:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:36 INFO - 'MOZ_NO_REMOTE': '1', 08:58:36 INFO - 'NO_EM_RESTART': '1', 08:58:36 INFO - 'PAGER': '/bin/cat', 08:58:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:36 INFO - 'PWD': '/builds/slave/test', 08:58:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:36 INFO - 'SHELL': '/bin/bash', 08:58:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfZIxxJtov/Listeners', 08:58:36 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 08:58:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:36 INFO - 'USER': 'cltbld', 08:58:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:36 INFO - 'XPC_FLAGS': '0x0', 08:58:36 INFO - 'XPC_SERVICE_NAME': '0', 08:58:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:58:36 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-p7eY8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:58:36 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:58:36 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-NRAawl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:58:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:58:36 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-OtoP__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:58:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:58:36 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-5xha19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:58:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:58:36 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-L8OZ4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:58:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:58:36 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-GWZ4EM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:58:37 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-BoLAkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:58:37 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-QTufE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:58:37 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-66VhCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:58:37 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-0zMR9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:58:37 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-CAkMFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:58:37 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-EGQGWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:58:37 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-2sgzQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:58:37 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-B9DJpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:58:37 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-aQrydZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:58:38 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-aFktCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:58:38 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-ENSRa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:58:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:40 INFO - Downloading blessings-1.6.tar.gz 08:58:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:58:40 INFO - Installing collected packages: blessings 08:58:40 INFO - Running setup.py install for blessings 08:58:41 INFO - Successfully installed blessings 08:58:41 INFO - Cleaning up... 08:58:41 INFO - Return code: 0 08:58:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:58:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:58:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:58:41 INFO - Reading from file tmpfile_stdout 08:58:41 INFO - Current package versions: 08:58:41 INFO - blessings == 1.6 08:58:41 INFO - blobuploader == 1.2.4 08:58:41 INFO - docopt == 0.6.1 08:58:41 INFO - functools32 == 3.2.3-2 08:58:41 INFO - jsonschema == 2.5.1 08:58:41 INFO - manifestparser == 1.1 08:58:41 INFO - mozInstall == 1.12 08:58:41 INFO - mozcrash == 1.0 08:58:41 INFO - mozdebug == 0.1 08:58:41 INFO - mozdevice == 0.48 08:58:41 INFO - mozfile == 1.2 08:58:41 INFO - mozhttpd == 0.7 08:58:41 INFO - mozinfo == 0.9 08:58:41 INFO - mozleak == 0.1 08:58:41 INFO - mozlog == 3.4 08:58:41 INFO - moznetwork == 0.27 08:58:41 INFO - mozprocess == 0.23 08:58:41 INFO - mozprofile == 0.28 08:58:41 INFO - mozrunner == 6.13 08:58:41 INFO - mozscreenshot == 0.1 08:58:41 INFO - mozsystemmonitor == 0.3 08:58:41 INFO - moztest == 0.7 08:58:41 INFO - mozversion == 1.4 08:58:41 INFO - psutil == 3.1.1 08:58:41 INFO - requests == 1.2.3 08:58:41 INFO - wsgiref == 0.1.2 08:58:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:42 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 0x10cf4f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f85aa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd63d30>, '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 0x10cbc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5bb20>, '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.RWI4iO7dKz/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.DfZIxxJtov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:58:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:58:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:58:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWI4iO7dKz/Render', 08:58:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:42 INFO - 'HOME': '/Users/cltbld', 08:58:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:42 INFO - 'LOGNAME': 'cltbld', 08:58:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:42 INFO - 'MOZ_NO_REMOTE': '1', 08:58:42 INFO - 'NO_EM_RESTART': '1', 08:58:42 INFO - 'PAGER': '/bin/cat', 08:58:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:42 INFO - 'PWD': '/builds/slave/test', 08:58:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:42 INFO - 'SHELL': '/bin/bash', 08:58:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfZIxxJtov/Listeners', 08:58:42 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 08:58:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:42 INFO - 'USER': 'cltbld', 08:58:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:42 INFO - 'XPC_FLAGS': '0x0', 08:58:42 INFO - 'XPC_SERVICE_NAME': '0', 08:58:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:58:42 INFO - Cleaning up... 08:58:42 INFO - Return code: 0 08:58:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:58:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf4f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f85aa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd63d30>, '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 0x10cbc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5bb20>, '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.RWI4iO7dKz/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.DfZIxxJtov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:58:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:58:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:58:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWI4iO7dKz/Render', 08:58:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:42 INFO - 'HOME': '/Users/cltbld', 08:58:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:42 INFO - 'LOGNAME': 'cltbld', 08:58:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:42 INFO - 'MOZ_NO_REMOTE': '1', 08:58:42 INFO - 'NO_EM_RESTART': '1', 08:58:42 INFO - 'PAGER': '/bin/cat', 08:58:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:42 INFO - 'PWD': '/builds/slave/test', 08:58:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:42 INFO - 'SHELL': '/bin/bash', 08:58:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfZIxxJtov/Listeners', 08:58:42 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 08:58:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:42 INFO - 'USER': 'cltbld', 08:58:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:42 INFO - 'XPC_FLAGS': '0x0', 08:58:42 INFO - 'XPC_SERVICE_NAME': '0', 08:58:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:58:42 INFO - Cleaning up... 08:58:43 INFO - Return code: 0 08:58:43 INFO - Running post-action listener: _resource_record_post_action 08:58:43 INFO - Running post-action listener: _start_resource_monitoring 08:58:43 INFO - Starting resource monitoring. 08:58:43 INFO - [mozharness: 2016-11-22 16:58:43.026939Z] Finished create-virtualenv step (success) 08:58:43 INFO - [mozharness: 2016-11-22 16:58:43.027968Z] Running install step. 08:58:43 INFO - Running pre-action listener: _resource_record_pre_action 08:58:43 INFO - Running main action method: install 08:58:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:58:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:58:43 INFO - Reading from file tmpfile_stdout 08:58:43 INFO - Detecting whether we're running mozinstall >=1.0... 08:58:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:58:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:58:43 INFO - Reading from file tmpfile_stdout 08:58:43 INFO - Output received: 08:58:43 INFO - Usage: mozinstall [options] installer 08:58:43 INFO - Options: 08:58:43 INFO - -h, --help show this help message and exit 08:58:43 INFO - -d DEST, --destination=DEST 08:58:43 INFO - Directory to install application into. [default: 08:58:43 INFO - "/builds/slave/test"] 08:58:43 INFO - --app=APP Application being installed. [default: firefox] 08:58:43 INFO - mkdir: /builds/slave/test/build/application 08:58:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:58:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:59:14 INFO - Reading from file tmpfile_stdout 08:59:14 INFO - Output received: 08:59:14 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:59:14 INFO - Running post-action listener: _resource_record_post_action 08:59:14 INFO - [mozharness: 2016-11-22 16:59:14.908183Z] Finished install step (success) 08:59:14 INFO - [mozharness: 2016-11-22 16:59:14.908348Z] Running run-tests step. 08:59:14 INFO - Running pre-action listener: _resource_record_pre_action 08:59:14 INFO - Running main action method: run_tests 08:59:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:59:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:59:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:59:14 INFO - 2016-11-22 08:59:14.923 screenresolution[2192:18793] starting screenresolution argv=screenresolution get 08:59:14 INFO - 2016-11-22 08:59:14.925 screenresolution[2192:18793] Display 0: 1600x1200x32@60 08:59:14 INFO - 2016-11-22 08:59:14.931 screenresolution[2193:18795] starting screenresolution argv=screenresolution list 08:59:14 INFO - Available Modes on Display 0 08:59:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:59:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:59:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:59:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:59:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:59:15 INFO - Intel Iris: 08:59:15 INFO - Chipset Model: Intel Iris 08:59:15 INFO - Type: GPU 08:59:15 INFO - Bus: Built-In 08:59:15 INFO - VRAM (Dynamic, Max): 1536 MB 08:59:15 INFO - Vendor: Intel (0x8086) 08:59:15 INFO - Device ID: 0x0a2e 08:59:15 INFO - Revision ID: 0x0009 08:59:15 INFO - Displays: 08:59:15 INFO - Display: 08:59:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:59:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:59:15 INFO - Display Serial Number: 1600x1200 60 08:59:15 INFO - Main Display: Yes 08:59:15 INFO - Mirror: Off 08:59:15 INFO - Online: Yes 08:59:15 INFO - Rotation: Supported 08:59:15 INFO - Return code: 0 08:59:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:59:15 INFO - Python 2.7.3 08:59:15 INFO - Return code: 0 08:59:15 INFO - grabbing minidump binary from tooltool 08:59:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:15 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 0x10cd63d30>, '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 0x10cbc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:59:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:59:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:59:15 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:59:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:59:15 INFO - Return code: 0 08:59:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:59:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:59:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:59:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:59:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:59:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0243 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:59:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWI4iO7dKz/Render', 08:59:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:15 INFO - 'HOME': '/Users/cltbld', 08:59:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:15 INFO - 'LOGNAME': 'cltbld', 08:59:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:59:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:59:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:15 INFO - 'MOZ_NO_REMOTE': '1', 08:59:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:59:15 INFO - 'NO_EM_RESTART': '1', 08:59:15 INFO - 'PAGER': '/bin/cat', 08:59:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:15 INFO - 'PWD': '/builds/slave/test', 08:59:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:59:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:15 INFO - 'SHELL': '/bin/bash', 08:59:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfZIxxJtov/Listeners', 08:59:15 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 08:59:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:15 INFO - 'USER': 'cltbld', 08:59:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:15 INFO - 'XPC_FLAGS': '0x0', 08:59:15 INFO - 'XPC_SERVICE_NAME': '0', 08:59:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:59:15 INFO - mozversion application_buildid: 20161122062251 08:59:15 INFO - mozversion application_changeset: 8be0fc0d33ac7e844dd011fd1f487545eab0d79c 08:59:15 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:59:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:59:15 INFO - mozversion application_name: Firefox 08:59:15 INFO - mozversion application_remotingname: firefox-dev 08:59:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:59:15 INFO - mozversion application_vendor: Mozilla 08:59:15 INFO - mozversion application_version: 52.0a2 08:59:15 INFO - mozversion platform_buildid: 20161122062251 08:59:15 INFO - mozversion platform_changeset: 8be0fc0d33ac7e844dd011fd1f487545eab0d79c 08:59:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:59:15 INFO - mozversion platform_version: 52.0a2 08:59:15 INFO - using testdate: 1479833955 08:59:15 INFO - actual date: 1479833955 08:59:15 INFO - starting webserver on 'localhost:53909' 08:59:15 INFO - SUITE-START | Running 2 tests 08:59:15 INFO - TEST-START | dromaeo_css 08:59:15 INFO - operating with platform_type : mac_ 08:59:15 INFO - Initialising browser for dromaeo_css test... 08:59:15 INFO - TEST-INFO | started process 2203 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpk6bXVX/profile http://localhost:53909/getInfo.html) 08:59:17 ERROR - PROCESS | 2203 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 08:59:17 INFO - PROCESS | 2203 | __metrics Screen width/height:1600/1200 08:59:17 INFO - PROCESS | 2203 | colorDepth:24 08:59:17 INFO - PROCESS | 2203 | Browser inner width/height: 1024/689 08:59:17 INFO - PROCESS | 2203 | __metrics 08:59:17 INFO - PROCESS | 2203 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:59:17 INFO - PROCESS | 2203 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:59:18 INFO - TEST-INFO | 2203: exit 0 08:59:18 INFO - Browser initialized. 08:59:18 INFO - Running cycle 1/1 for dromaeo_css test... 08:59:18 INFO - TEST-INFO | started process 2205 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpk6bXVX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:59:30 INFO - PROCESS | 2205 | RSS: Main: 217329664 08:59:30 INFO - PROCESS | 2205 | 09:00:21 INFO - PROCESS | 2205 | 1479834021896 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:02:34 INFO - PROCESS | 2205 | Cycle 1(1): loaded http://localhost:53909/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53909/tests/dromaeo/cssquery-ext.html) 09:02:36 INFO - PROCESS | 2205 | RSS: Main: 389275648 09:02:36 INFO - PROCESS | 2205 | 09:05:39 INFO - PROCESS | 2205 | Cycle 1(1): loaded http://localhost:53909/tests/dromaeo/cssquery-ext.html (next: http://localhost:53909/tests/dromaeo/cssquery-jquery.html) 09:05:40 INFO - PROCESS | 2205 | RSS: Main: 349483008 09:05:40 INFO - PROCESS | 2205 | 09:08:42 INFO - PROCESS | 2205 | Cycle 1(1): loaded http://localhost:53909/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53909/tests/dromaeo/cssquery-mootools.html) 09:08:43 INFO - PROCESS | 2205 | RSS: Main: 317546496 09:08:43 INFO - PROCESS | 2205 | 09:11:45 INFO - PROCESS | 2205 | Cycle 1(1): loaded http://localhost:53909/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53909/tests/dromaeo/cssquery-prototype.html) 09:11:46 INFO - PROCESS | 2205 | RSS: Main: 323903488 09:11:46 INFO - PROCESS | 2205 | 09:14:49 INFO - PROCESS | 2205 | Cycle 1(1): loaded http://localhost:53909/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53909/tests/dromaeo/cssquery-yui.html) 09:14:50 INFO - PROCESS | 2205 | RSS: Main: 313782272 09:14:50 INFO - PROCESS | 2205 | 09:17:53 INFO - PROCESS | 2205 | Cycle 1(1): loaded http://localhost:53909/tests/dromaeo/cssquery-yui.html (next: http://localhost:53909/tests/dromaeo/cssquery-dojo.html) 09:17:53 INFO - PROCESS | 2205 | RSS: Main: 526770176 09:17:53 INFO - PROCESS | 2205 | 09:17:53 INFO - PROCESS | 2205 | __start_tp_report 09:17:53 INFO - PROCESS | 2205 | _x_x_mozilla_page_load 09:17:53 INFO - PROCESS | 2205 | _x_x_mozilla_page_load_details 09:17:53 INFO - PROCESS | 2205 | |i|pagename|runs| 09:17:53 INFO - PROCESS | 2205 | |0;dojo.html;2761.5305398082323;3312.0891754767445;3351.4972754086884;3371.6459771723953;3399.592048954126;5696.344920334162;5737.684427356494;5750.539956803456;5750.856228594282;5796.101604251337;5674.205611214423;5689.8577535561635;5782.653040817554;5790.565707571938;5811.796587119452;5235.1885457754015;5241.764120614563;5247.763850626712;5260.579153667697;5299.072662284085;5164.457731938149;5174.767135478894;5196.974015129938;5314.335708036496;5329.333833270841;5172.120739474299;5235.345581802275;5253.868653283679;5255.395629502599;5307.124324486455;5240.318758561362;5255.369355677305;5317;5334.466553344634;5337.01265265927;5191.792328306863;5232.686038837682;5264.578833693296;5288.1538953767595;5312.043832110218;5577.442255774431;5633.070543360341;5638.915416268759;5658.603897727161;5669.0079236133515;6175.691215439255;6253.749850005995;6273.466755325803;6288.088227207029;6288.434040936292;6303.149074874879;6340.270868850095;6367.108604795332;6384.074309225136;6388.456981156607;5709.400512946164;5741.799037033684;5834.970825145932;5869.031609784381;5966.254218222723;6296.055591661196;6315.431611155018;6333.809985700436;6337.2395312562785;6338.112664227012;6221.471174950089;6237.438630523275;6238.282597501255;6238.812835614848;6260.843478912972;5882.1470886722045;5930.525557955353;5971.970140149271;6186.81439556814;6346.777862774781;6143.204841796972;6210.7515699371515;6223.96888015557;6224.848403045451;6231.158793562811;6192.195014648067;6226.59527130735;6234.719437625317;6245.531585131133;6255.937440625627;4766.642501812308;4905.5094490550655;4915.5330243626795;4917.1149193144865;4945.455999840015;4429.15845989261;4446.688731788809;4454.398656181439;4471.19518486669;4519.502854685982;4854.951450485521;5713.600047996683;5745.253117094751;5753.884922301614;5756.798512052058;6340.239171299381;6346.270178929482;6350.872982540416;6371.96814015927;6378.075179099004;5564.693941833238;5674.687892165971;5695.231143795618;5696.401877802854;5759.2512973313205;5410.729463526808;5428.375736790241;5431;5437.347518297751;5461.481159289861;5402.270693456412;6265.8120256392385;6332.746690132435;6349;6364.77723279683;6161.260648722003;6239.313675495601;6293.37066293367;6334.429901308904;6338.8732225354315;6263.4362907338555;6284.685765711788;6308.684565771601;6350.333215012357;6371.522135839923;6250.749970001149;6252.968735156477;6262.749490020348;6277.654728989949;6280.497560195265;6179.876402471836;6282.528810339151;6286.214223222098;6312.368763123651;6352.714127864165;5866.090755932837;6128.540359472968;6137.009281402767;6156.26124865019;6215.782447614311;6199.9770037943235;6200.596961197508;6204.503639708722;6210.875782484415;6220.973539365954;5861.208736820475;5933.703314834327;6029.8191054268445;6071.5446341523675;6081.787137450168;5918.112283157561;6014.0377539593455;6017.849553761191;6025.457708806229;6051.152838602511;5890.852728681646;5915.08316210988;5917.378675239038;5922.1412895130825;5923.585349025527 09:17:53 INFO - PROCESS | 2205 | |1;ext.html;450.7903824721505;454.77943197549183;486.71770373183557;506.2077848167615;518.7587771686165;31033.534496982564;31510.68489315106;31523.527147092813;31624.84187579062;38234.85295441141;38408.655697050606;40524.39213411801;40535.78392648218;40544.79727601358;40869;9376.156145946881;9543;9564.473953932531;9572.904270957271;9576.952115239414;8355.749327520183;8505.659773609048;9396;9494.762630934212;10196.439195844225;11241.100711943065;11418.086553075735;11425.200235983484;11433.771324573503;11520.539178432853;11402.030827379682;11476.196666233367;11476.71308217293;11477.655670329903;11486.483108260065;8382.580870956488;8618.956905215497;11307.660770176908;11377.601783937605;11386.772264554662;38439.423408648894;39094;39227.8038609808;40216.59783402158;40358.192836143404;3241.773075884689;3330.6835850594157;3392.6607339266125;3748.906277343061;3782.583915769263;28705.28236794076;29535;30504.77980880762;30978;32037.51943720846;3222.1622378181605;3241.2383089973837;3246.1559994401628;3268.313654132635;3361.798292102482;500246;512925;513758;518852;531056.3447182778;51692;63720.72558548896;65553.67223163854;65840.34159658437;69247.96128058083;66897.3310266891;67840.66079669668;67962.66018669875;67985.66007170032;67996;16367.91816040912;16544.917275413547;16551.172441377883;16553.586160346087;16564.917175414288;12506.374681265901;12751.681207969876;12798.936005319914;12820.102592818414;12829.23024618526;15298.617534561503;17536.123193840256;17567;18421.447356579323;19441.833489990644;7578.787394016931;8038.397120216007;8236.705317872778;8260.230640862737;8278.254957053894;757.8257000889827;895.901450840407;978.3689520259535;996.236661109846;1097.9203334646393;27420.862895685394;29160;29548.852255738584;29610.40779184404;29703.405931881673;1048.7325731938306;1054.5887104029434;1114.8383484394717;1163.203205804027;1188.5542921404474;14757.77863332051;15234.619134521505;15253.694926101638;15256;15276.38894444232;685578;696654;700214;700418;700932.9906700868;48437.03125937532;49388;49462.75268623706;56823;57354.71322643443;1095.39205740813;1233.0751936047964;1257.126297223421;1355.2681551962028;1371.149887070023;29487;30582.235444114074;30626;30967.380652387277;31238.687613123577;47626.285605716635;48117.75941120272;48222.79443013812;48356.75821620869;48378;597189;601961;604769;604899.9510004844;605767.9423205712;26261.080862169732;26688.332791680365;27115.59326610063;27126;27871.581926271527;45293;45419.54580454285;45778.77110614425;45813;45897.77051114723;30365.240868978453;36255.63744362522;37262.88211353664;37268.06829829276;37705.81147094247;36908.26183476369;37611.43582846205;38377.80811095927;38760;39258;36387;36904;37011.6298837019;37099.44350834793;37241.44137837989;27422.86288568624;31843.20391990219;33059.504107437926;33301.83349083239;34021;24855.87572062128;24859.875700621382;25143.49713005693;25246.50332308288;25343.61984570196 09:17:53 INFO - PROCESS | 2205 | |2;jquery.html;327.6657809034783;368.96863766579844;370.65821725354203;382.9100161462057;402.9476168098147;12369.196002259858;15135;15595.922020389897;20502.897485512553;21092;17052.829471705278;19697.310594129172;20663.483412914647;20822.47943801402;21188.364349069554;5589.385167631557;6025.246844144482;6036.5170786336985;6066.998945174041;6070.423309785585;4683.8360657377;6022.367651396606;6057.878842423149;6079.331273559905;6096.298925623566;5681.744321505522;6011.5491338149595;6028.487578555828;6034.125051867477;6037.72830222641;5911.349751527328;5919.378465261172;5919.5560332974765;5919.822405327847;5944.405559444042;4965.602751779849;5080.568151707109;5185.377754669426;5202.583793296565;5227.738613069369;25396.23811285646;25805.83873725687;25857.612135817788;25873.74126258751;26219.606705899427;1907.246637578149;2211.2150186683657;2401.1955994741784;2420.213430635033;2482.081629796987;9823.017698230191;9983.950080249624;10372.222083343704;12584.496620135184;14750.778738318933;1822.7083666613278;1844.5480857190075;1860.209411000333;1877.586930875219;1918.3381733301987;113815.43092284485;123670.14494782586;126609.60513553824;134050.32974835063;135040;11535.942320288512;11790.705232369259;11793.631319879523;11793.941030294794;11796.410179490991;15137.091774493565;15138.848611513964;15169.24153792306;15170.24148792578;15179.165145916859;8516.70191543293;8655.047944726075;8703.564821759013;8857.557122143868;8868.556572171366;8587.441816282048;8615.224629783224;8623.741287761377;8769.517676527852;8772.29821614282;4507.414036175276;4593.517680643551;4632.2125238709505;4663.696859704109;4682.555157260055;1784.6073863749955;1796.1680449626367;1802.4592622213545;1834;1900.828925396721;870.4907629036958;942.7972985808084;945.5272363818091;955.0831202045974;982.1553464020936;20535.794642053686;20546.17815287372;21524.0314185862;21568.784312156993;21621.810800405816;883.6907082521067;931.7018554062635;980.3823591137681;980.4460479828874;981.8478135888948;10244.282900197064;10931.125509959184;11119.666410007712;11150.49822757978;11184.552617895193;571072;572889;579891;591190;595777;98786;118145;118695;121775;125790;926.6200857648466;937.7936853892132;950.3015283766426;950.9144177023962;978.8972157923315;15125.924370378078;19468.61062778765;20116.177926193977;20205.292814751414;20247.392578222676;37678.811605941795;55217.44782552123;55440.72279638576;57301;57345;67748;75939.24060759542;79589;80252;80956.59521702354;7927.722529711433;8167.469114507776;8295.95852020736;8524.573771311534;8606.096359882122;10098.444585547866;12758.744825103631;12937.547185848827;13062.34688265564;13239.735205296032;11029.724256893642;11302.604408845651;11628.360440175871;11630.767384652429;11779.293242405483;11007.83488247693;11039.558417663204;11129.721756956142;11191.608293709682;11586.826197607212;11567.595134170264;11665;11707;11811.704707382385;11834.644960651194;7069.151701795612;8241.299489543457;8367.748967530984;8370.748877533684;8376.581170941306;8313;9942.508623706502;10506.737331566772;10867.0762985144;11492.885071149181 09:17:53 INFO - PROCESS | 2205 | |3;mootools.html;1441.783732440134;1470.9705805883882;1612.435647523367;1785.071762683404;1804.9729254061192;7280.235575264601;8230.711925082616;8663.39356245063;8663.566821658924;8667.60995755191;7580.658870350833;7809.960950195241;7810.609469526529;8216.95891520543;8477.618507167177;2040.6224848403092;2248.9887550562225;2290.75947025562;2337.742848286687;2339.543788961153;2329.778671026258;2358.2571489980687;2362.4802543440414;2374;2378.203301893859;2288.2677543186187;2300.0914638717695;2327.905884234407;2333.6382860656627;2344.15610380263;1919.500929758259;2327.755585663498;2330.685357476736;2331.778481044298;2337.439014636492;2147.8174355179826;2224.6996655451467;2319.489712263299;2358.575456417844;2360.44529535559;8492.108328625529;8533.061363250037;8597.742067737978;8605;8622.13778622139;5101.081805275049;5297.417284098746;5299.54953828925;5307.840764777063;5309.36287645485;3230.720216642446;3343.626025521882;3386.221169131089;3408.011676613779;3411.77823441478;1069.288922866293;1078.665613659768;1084.7004350776326;1106.2035334559184;1117.050507068993;11243.60647377338;11284.774304513945;11425.31448113116;11442.084633229239;11507.136964727679;4567.6802623816675;4667.299905014207;4774.9972505773485;4778.5699287064335;4846.709197448165;9731.464769437749;9735.610575577039;9746.269029822792;9748;9756.02439756033;5291.523762861361;5309.442508536626;5310.123829568078;5313.07021271276;5327.680339179662;4056.4320995060125;4158.979205103955;4229.2598795210715;4871.269309603517;4871.610271178368;3846.769193848322;3865.130345672201;3907.1208977980295;3954.0312623406876;3956.1098752780968;1249.1755441408627;1259.3577275589516;1262.741138066694;1267.9112462127748;1272.204871955028;851.7700220940311;898.4115404410122;899.3974037394961;904.3533873280636;934.8688087414305;5795.797147099831;5818.679972601548;5823.941760582425;5978.701064946736;6123.295820980555;440.0626665203097;442.4911351945224;444.2026562320642;450.9954900450953;451.68156449702883;3772.1135533149686;3901.4147877818555;3911.9022024449046;3942.388929715841;3982.2831890259454;9982.60069597208;10132.493375331203;10139.543720532594;10537.84193237102;10555.419451930064;9971.501424928869;10084.899151008543;10228.488575571191;10444.268901176845;10522.73693157662;7988.84022319562;8020.518768873886;8082.919170808216;8171.877421838677;8173.386995975326;5743.827685169452;5798.768049277982;5845.883082338244;5869.647821130746;5880.411958804086;8081.9191808083515;9143.222826059859;9282.443053416546;9603.90396096058;9640.662576809778;9544.475053872104;9680.27397945143;9905.959874213391;9921.206303495559;9925.553350099119;6700.765473208415;6933.687984040831;7144.785656430316;7338.596377199306;7440.330370266702;7995.040595128622;8048.39637027237;8183.345332373515;8204.589770511331;8219.095899451175;7019.684114214975;7084.2915708428745;7115.893261601183;7257.782266532013;7356.301151390609;7050.506464547432;7059.223485416703;7100.112485939258;7128.643567821485;7264.67308971087;6719.529632925648;6873.1064961554675;6878.518503704692;6881.380675739208;6910.378065974087;4168.931486902279;4195.307774217342;4856.975715121402;4879.316895634543;5093.962415034016;4959.107360675113;4967.677100988424;5003.124453220745;5027.773750181177;5087.720175390389 09:17:53 INFO - PROCESS | 2205 | |4;prototype.html;257.66117555414627;309.4430025953285;310.18421551320023;314.9039542939403;318.5460718476174;5312.096943519601;5571.442855714432;5592.7762889484475;5608.775648974041;5623.831285061445;4617.515160908099;5398.406175320712;5469.425710300421;5473.972630136854;5516.99999999999;3881.2625601135833;4062.573429789874;4072.165206132736;4081.428599996003;4087.591240875918;3691.7969511676847;3742.494763206973;3892.70804689648;4040.070783719746;4145.854895078673;3764.9623503764888;4077.3068578341754;4086.3053280942313;4107.377585853586;4111.691623128262;3543.2559162575762;3563.0914116900153;3690.833912473945;4124.4019617155345;4163.938195760062;3248.772585918987;3262.075375528426;3371.7302615790923;3432.2963792422493;3601.3877640801124;4274.418465167908;4454.265046267395;4855;5238.476152384769;5307.230451584537;4190.580941905816;4474.239379305526;4786.282057691339;4800.663953523254;4816.662833601649;4979.8008079676765;5237.633365664405;5347.075052980924;5704.372519022904;5813.505852002585;3451.9654803451895;3988.3419235826345;3990.670122916639;4051.3112770828734;4054;5710.828675139753;5926.940730592725;5929.318128415244;5966.8961242170335;6012.428819262163;3595.3168897909313;3755.4179102239195;3846.6922646188236;3989.7406668567032;4066.532348779869;3485.7734247273847;3765.4916586260506;3778.1876896467393;4154.979225103855;4163.542010378856;4043.4137050128156;4611.953880461219;4743.478217396084;4806.759662016955;4828.782704778293;4781.665283430196;5722.971385143048;5755.539556835443;5783.508401785811;5820.605442890591;1178.950733846877;1256.4984843112393;1335.8396992360786;1336.8061631063442;1368.2406264523286;759.343168159538;814.0882211922684;864.9870251946226;939.8778158839444;939.9812003760023;4577.404937358122;5107.821226257063;5144.536991670768;5145.706747681487;5147.742612869416;4674.859754207379;5565.493738944439;5597.972010139923;5650.463205995424;5697.857553561112;4638.466576343696;5053.36832895888;5116.692998420032;5132.28148059272;5165.457626949116;2904.840233787162;2907.6074729911616;2910.592517047615;2927.6486821581548;2938.6032885560176;5164.948350516447;5240.68555886648;5719.056355701346;5719.7712091516705;5773.391630231105;5150.639455238097;5355.33106886058;5596.552275817925;5693.430656934356;5747.770089196385;4697.050723949591;4998.700077995332;5711.343195532567;5720.34110108064;5814.883702325845;5074.517920797518;5127.743612819419;5516.11114164505;5627.577931655224;5652.63257888236;5413.458654134555;5935.842510710543;5945.375735547878;5978.730957106854;5992.970035149797;5406.648567843077;5879.73541190656;5879.853003674943;5975.267172520094;6229.501639868709;3350.5811773528308;4008.9599104008585;4016.256992456405;4016.49793775778;4042.8584999524874;5853.209816674865;6020.337762846171;6116.318012546585;6122.418370254819;6140.662263575547;5822.650640961556;5825.679587622722;5862.824115276549;5876.147958546072;5881.9705901470215;5102.846914592568;5809.518572763918;5882.470577648032;5883.911741323969;5899.941000590111;4990.575801056805;5250.842474725764;5303.0719624066405;5757.97121014409;5771.971140144273;4876.634252431011;5336.332958380202;5812.244408226903;5840.853978650398;5858.451573565512;5266.578673706171;5733.928910473611;5749.080147176432;5764.625299355528;5785.82642520725 09:17:53 INFO - PROCESS | 2205 | |5;yui.html;658.2068607328168;697.3479796390372;744.1330849560262;768.9000429944107;775.0699161006789;14197.858021419783;14642.633934151638;15896.602584935383;16431.4249001285;16716.41492547761;14790.112593244412;15120.621984450394;15254.466093686724;15295.617609559766;15306.928515003954;810.2059981218409;835.3943391041507;837.4431003382743;837.7528629054443;840.756180707594;643.8519140597665;645.9450946669539;664.7939138866942;814.1614137438448;824.3858325547476;852.4246133859651;858.0132847225678;858.0518527027638;858.4677499950039;858.8196478739472;833.3125171733344;854.8589482735341;854.9444286121383;855.70050482331;857.1471385970937;502.15888386952076;513.0867056639188;517.2938938349164;635.0125554762343;636.9044643303541;12449.875501244962;12488;12542.933850622709;14280.357383917748;14821.925890370587;13177.86822131776;13461.125026873222;13542.932285338511;13566.932165339209;13586.796198057036;11161.665150045512;11251.381174035425;11495.310281383145;11502.597409090642;11543.134264930164;3719.5722491913507;3729.1422972716155;3750.887473375803;3802.372608519591;3820.6943444524372;90101;95528;96175.51912240394;97862.51068744682;98504;10113.69658910234;10340.638077667245;10432.582696692047;10539.209559283085;10823.404712740874;19151.52121196981;19870.205191792167;20003.199872005247;20036.599268014557;20112.79887201109;7634.312911837961;7645.350145237717;7705;7721.343685786771;7756.53460792343;4192.559781222928;4195.559466255999;4267.274563324242;4384.583464570861;4479.664025198123;2908.520094184436;3533.2050288685355;3576.856925722942;3582.874599389009;3586.318599466093;658.4337469775988;668.4084585142184;670.637855558003;674.7065026713395;675.9121314229119;522.5532169994676;574.0556784090148;646.6863571167979;661.6856992928417;670.7719375412383;8801.694083107304;9313.813723725487;9425.198858097001;9457.148856602802;9613.182879455184;628.0986783965025;656.5273003437518;661.2692974263466;674.3324109132022;683.4122654517112;4434.5343738907595;4539.114872599877;4552.726836389827;4572.9314060289125;4638.304254361873;78076.43847123026;112889;117925.41037294759;124934.75065249413;125393.11910321352;68956;68998;72441;91155.53066816012;105983;92368;94976;95525.5223723877;97294.0270597285;97454.99999999859;8561.1866872647;8842.734717958472;8885.222955408815;8937.508437036025;9094.317926155432;56609.433905660415;57630;58176.4182358171;58562.7071864638;61202.69398652978;53051;53825.73087134539;73695;74373.62813185899;77335.453290935;12203.023758099509;12669;12828.55100071493;12859.678508037374;12918.483260669467;13806.553423352309;13806.585802425943;13996.440142394598;14256.358463869357;14363.425462981364;12465.127441079037;13191.406386712813;13360.532381366605;13485.123466974615;13853.930730346283;12733.61799146027;13363.465461381436;13367.465301387836;13427.798583021067;13453.260070695815;9002.549872506217;10022.599096036078;12156.75686486283;12239.449224785085;12349.3825308736;10683.30558513694;10902;11659.06727461784;11771.587994420153;11927.522899083939;9894.45580493051;10636.787264254826;11386.918242766924;11614.70963225926;11626.88373116258 09:17:53 INFO - PROCESS | 2205 | __end_tp_report 09:17:53 INFO - PROCESS | 2205 | __start_cc_report 09:17:53 INFO - PROCESS | 2205 | _x_x_mozilla_cycle_collect,1955 09:17:53 INFO - PROCESS | 2205 | __end_cc_report 09:17:53 INFO - PROCESS | 2205 | __startTimestamp1479835073776__endTimestamp 09:17:53 INFO - PROCESS | 2205 | 09:17:53 INFO - PROCESS | 2205 | ------- Summary: start ------- 09:17:53 INFO - PROCESS | 2205 | Number of tests: 6 09:17:53 INFO - PROCESS | 2205 | 09:17:53 INFO - PROCESS | 2205 | [#0] dojo.html Cycles:165 Average:5759.27 Median:5922.14 stddev:661.90 (11.2%) stddev-sans-first:620.75 09:17:53 INFO - PROCESS | 2205 | Values: 2761.5 3312.1 3351.5 3371.6 3399.6 5696.3 5737.7 5750.5 5750.9 5796.1 5674.2 5689.9 5782.7 5790.6 5811.8 5235.2 5241.8 5247.8 5260.6 5299.1 5164.5 5174.8 5197.0 5314.3 5329.3 5172.1 5235.3 5253.9 5255.4 5307.1 5240.3 5255.4 5317.0 5334.5 5337.0 5191.8 5232.7 5264.6 5288.2 5312.0 5577.4 5633.1 5638.9 5658.6 5669.0 6175.7 6253.7 6273.5 6288.1 6288.4 6303.1 6340.3 6367.1 6384.1 6388.5 5709.4 5741.8 5835.0 5869.0 5966.3 6296.1 6315.4 6333.8 6337.2 6338.1 6221.5 6237.4 6238.3 6238.8 6260.8 5882.1 5930.5 5972.0 6186.8 6346.8 6143.2 6210.8 6224.0 6224.8 6231.2 6192.2 6226.6 6234.7 6245.5 6255.9 4766.6 4905.5 4915.5 4917.1 4945.5 4429.2 4446.7 4454.4 4471.2 4519.5 4855.0 5713.6 5745.3 5753.9 5756.8 6340.2 6346.3 6350.9 6372.0 6378.1 5564.7 5674.7 5695.2 5696.4 5759.3 5410.7 5428.4 5431.0 5437.3 5461.5 5402.3 6265.8 6332.7 6349.0 6364.8 6161.3 6239.3 6293.4 6334.4 6338.9 6263.4 6284.7 6308.7 6350.3 6371.5 6250.7 6253.0 6262.7 6277.7 6280.5 6179.9 6282.5 6286.2 6312.4 6352.7 5866.1 6128.5 6137.0 6156.3 6215.8 6200.0 6200.6 6204.5 6210.9 6221.0 5861.2 5933.7 6029.8 6071.5 6081.8 5918.1 6014.0 6017.8 6025.5 6051.2 5890.9 5915.1 5917.4 5922.1 5923.6 09:17:53 INFO - PROCESS | 2205 | 09:17:53 INFO - PROCESS | 2205 | [#1] ext.html Cycles:180 Average:72831.98 Median:36906.13 stddev:163294.23 (442.5%) stddev-sans-first:163661.89 09:17:53 INFO - PROCESS | 2205 | Values: 450.8 454.8 486.7 506.2 518.8 31033.5 31510.7 31523.5 31624.8 38234.9 38408.7 40524.4 40535.8 40544.8 40869.0 9376.2 9543.0 9564.5 9572.9 9577.0 8355.7 8505.7 9396.0 9494.8 10196.4 11241.1 11418.1 11425.2 11433.8 11520.5 11402.0 11476.2 11476.7 11477.7 11486.5 8382.6 8619.0 11307.7 11377.6 11386.8 38439.4 39094.0 39227.8 40216.6 40358.2 3241.8 3330.7 3392.7 3748.9 3782.6 28705.3 29535.0 30504.8 30978.0 32037.5 3222.2 3241.2 3246.2 3268.3 3361.8 500246.0 512925.0 513758.0 518852.0 531056.3 51692.0 63720.7 65553.7 65840.3 69248.0 66897.3 67840.7 67962.7 67985.7 67996.0 16367.9 16544.9 16551.2 16553.6 16564.9 12506.4 12751.7 12798.9 12820.1 12829.2 15298.6 17536.1 17567.0 18421.4 19441.8 7578.8 8038.4 8236.7 8260.2 8278.3 757.8 895.9 978.4 996.2 1097.9 27420.9 29160.0 29548.9 29610.4 29703.4 1048.7 1054.6 1114.8 1163.2 1188.6 14757.8 15234.6 15253.7 15256.0 15276.4 685578.0 696654.0 700214.0 700418.0 700933.0 48437.0 49388.0 49462.8 56823.0 57354.7 1095.4 1233.1 1257.1 1355.3 1371.1 29487.0 30582.2 30626.0 30967.4 31238.7 47626.3 48117.8 48222.8 48356.8 48378.0 597189.0 601961.0 604769.0 604900.0 605767.9 26261.1 26688.3 27115.6 27126.0 27871.6 45293.0 45419.5 45778.8 45813.0 45897.8 30365.2 36255.6 37262.9 37268.1 37705.8 36908.3 37611.4 38377.8 38760.0 39258.0 36387.0 36904.0 37011.6 37099.4 37241.4 27422.9 31843.2 33059.5 33301.8 34021.0 24855.9 24859.9 25143.5 25246.5 25343.6 09:17:53 INFO - PROCESS | 2205 | 09:17:53 INFO - PROCESS | 2205 | [#2] jquery.html Cycles:180 Average:34457.70 Median:348.32 stddev:97275.66 (27927.3%) stddev-sans-first:97514.78 09:17:53 INFO - PROCESS | 2205 | Values: 327.7 369.0 370.7 382.9 402.9 12369.2 15135.0 15595.9 20502.9 21092.0 17052.8 19697.3 20663.5 20822.5 21188.4 5589.4 6025.2 6036.5 6067.0 6070.4 4683.8 6022.4 6057.9 6079.3 6096.3 5681.7 6011.5 6028.5 6034.1 6037.7 5911.3 5919.4 5919.6 5919.8 5944.4 4965.6 5080.6 5185.4 5202.6 5227.7 25396.2 25805.8 25857.6 25873.7 26219.6 1907.2 2211.2 2401.2 2420.2 2482.1 9823.0 9984.0 10372.2 12584.5 14750.8 1822.7 1844.5 1860.2 1877.6 1918.3 113815.4 123670.1 126609.6 134050.3 135040.0 11535.9 11790.7 11793.6 11793.9 11796.4 15137.1 15138.8 15169.2 15170.2 15179.2 8516.7 8655.0 8703.6 8857.6 8868.6 8587.4 8615.2 8623.7 8769.5 8772.3 4507.4 4593.5 4632.2 4663.7 4682.6 1784.6 1796.2 1802.5 1834.0 1900.8 870.5 942.8 945.5 955.1 982.2 20535.8 20546.2 21524.0 21568.8 21621.8 883.7 931.7 980.4 980.4 981.8 10244.3 10931.1 11119.7 11150.5 11184.6 571072.0 572889.0 579891.0 591190.0 595777.0 98786.0 118145.0 118695.0 121775.0 125790.0 926.6 937.8 950.3 950.9 978.9 15125.9 19468.6 20116.2 20205.3 20247.4 37678.8 55217.4 55440.7 57301.0 57345.0 67748.0 75939.2 79589.0 80252.0 80956.6 7927.7 8167.5 8296.0 8524.6 8606.1 10098.4 12758.7 12937.5 13062.3 13239.7 11029.7 11302.6 11628.4 11630.8 11779.3 11007.8 11039.6 11129.7 11191.6 11586.8 11567.6 11665.0 11707.0 11811.7 11834.6 7069.2 8241.3 8367.7 8370.7 8376.6 8313.0 9942.5 10506.7 10867.1 11492.9 09:17:53 INFO - PROCESS | 2205 | 09:17:53 INFO - PROCESS | 2205 | [#3] mootools.html Cycles:180 Average:5527.16 Median:5057.75 stddev:3087.35 (61.0%) stddev-sans-first:3080.75 09:17:53 INFO - PROCESS | 2205 | Values: 1441.8 1471.0 1612.4 1785.1 1805.0 7280.2 8230.7 8663.4 8663.6 8667.6 7580.7 7810.0 7810.6 8217.0 8477.6 2040.6 2249.0 2290.8 2337.7 2339.5 2329.8 2358.3 2362.5 2374.0 2378.2 2288.3 2300.1 2327.9 2333.6 2344.2 1919.5 2327.8 2330.7 2331.8 2337.4 2147.8 2224.7 2319.5 2358.6 2360.4 8492.1 8533.1 8597.7 8605.0 8622.1 5101.1 5297.4 5299.5 5307.8 5309.4 3230.7 3343.6 3386.2 3408.0 3411.8 1069.3 1078.7 1084.7 1106.2 1117.1 11243.6 11284.8 11425.3 11442.1 11507.1 4567.7 4667.3 4775.0 4778.6 4846.7 9731.5 9735.6 9746.3 9748.0 9756.0 5291.5 5309.4 5310.1 5313.1 5327.7 4056.4 4159.0 4229.3 4871.3 4871.6 3846.8 3865.1 3907.1 3954.0 3956.1 1249.2 1259.4 1262.7 1267.9 1272.2 851.8 898.4 899.4 904.4 934.9 5795.8 5818.7 5823.9 5978.7 6123.3 440.1 442.5 444.2 451.0 451.7 3772.1 3901.4 3911.9 3942.4 3982.3 9982.6 10132.5 10139.5 10537.8 10555.4 9971.5 10084.9 10228.5 10444.3 10522.7 7988.8 8020.5 8082.9 8171.9 8173.4 5743.8 5798.8 5845.9 5869.6 5880.4 8081.9 9143.2 9282.4 9603.9 9640.7 9544.5 9680.3 9906.0 9921.2 9925.6 6700.8 6933.7 7144.8 7338.6 7440.3 7995.0 8048.4 8183.3 8204.6 8219.1 7019.7 7084.3 7115.9 7257.8 7356.3 7050.5 7059.2 7100.1 7128.6 7264.7 6719.5 6873.1 6878.5 6881.4 6910.4 4168.9 4195.3 4857.0 4879.3 5094.0 4959.1 4967.7 5003.1 5027.8 5087.7 09:17:53 INFO - PROCESS | 2205 | 09:17:53 INFO - PROCESS | 2205 | [#4] prototype.html Cycles:180 Average:4586.56 Median:5145.12 stddev:1430.29 (27.8%) stddev-sans-first:1396.90 09:17:53 INFO - PROCESS | 2205 | Values: 257.7 309.4 310.2 314.9 318.5 5312.1 5571.4 5592.8 5608.8 5623.8 4617.5 5398.4 5469.4 5474.0 5517.0 3881.3 4062.6 4072.2 4081.4 4087.6 3691.8 3742.5 3892.7 4040.1 4145.9 3765.0 4077.3 4086.3 4107.4 4111.7 3543.3 3563.1 3690.8 4124.4 4163.9 3248.8 3262.1 3371.7 3432.3 3601.4 4274.4 4454.3 4855.0 5238.5 5307.2 4190.6 4474.2 4786.3 4800.7 4816.7 4979.8 5237.6 5347.1 5704.4 5813.5 3452.0 3988.3 3990.7 4051.3 4054.0 5710.8 5926.9 5929.3 5966.9 6012.4 3595.3 3755.4 3846.7 3989.7 4066.5 3485.8 3765.5 3778.2 4155.0 4163.5 4043.4 4612.0 4743.5 4806.8 4828.8 4781.7 5723.0 5755.5 5783.5 5820.6 1179.0 1256.5 1335.8 1336.8 1368.2 759.3 814.1 865.0 939.9 940.0 4577.4 5107.8 5144.5 5145.7 5147.7 4674.9 5565.5 5598.0 5650.5 5697.9 4638.5 5053.4 5116.7 5132.3 5165.5 2904.8 2907.6 2910.6 2927.6 2938.6 5164.9 5240.7 5719.1 5719.8 5773.4 5150.6 5355.3 5596.6 5693.4 5747.8 4697.1 4998.7 5711.3 5720.3 5814.9 5074.5 5127.7 5516.1 5627.6 5652.6 5413.5 5935.8 5945.4 5978.7 5993.0 5406.6 5879.7 5879.9 5975.3 6229.5 3350.6 4009.0 4016.3 4016.5 4042.9 5853.2 6020.3 6116.3 6122.4 6140.7 5822.7 5825.7 5862.8 5876.1 5882.0 5102.8 5809.5 5882.5 5883.9 5899.9 4990.6 5250.8 5303.1 5758.0 5772.0 4876.6 5336.3 5812.2 5840.9 5858.5 5266.6 5733.9 5749.1 5764.6 5785.8 09:17:53 INFO - PROCESS | 2205 | 09:17:53 INFO - PROCESS | 2205 | [#5] yui.html Cycles:180 Average:20663.17 Median:4562.83 stddev:30538.41 (669.3%) stddev-sans-first:30587.13 09:17:53 INFO - PROCESS | 2205 | Values: 658.2 697.3 744.1 768.9 775.1 14197.9 14642.6 15896.6 16431.4 16716.4 14790.1 15120.6 15254.5 15295.6 15306.9 810.2 835.4 837.4 837.8 840.8 643.9 645.9 664.8 814.2 824.4 852.4 858.0 858.1 858.5 858.8 833.3 854.9 854.9 855.7 857.1 502.2 513.1 517.3 635.0 636.9 12449.9 12488.0 12542.9 14280.4 14821.9 13177.9 13461.1 13542.9 13566.9 13586.8 11161.7 11251.4 11495.3 11502.6 11543.1 3719.6 3729.1 3750.9 3802.4 3820.7 90101.0 95528.0 96175.5 97862.5 98504.0 10113.7 10340.6 10432.6 10539.2 10823.4 19151.5 19870.2 20003.2 20036.6 20112.8 7634.3 7645.4 7705.0 7721.3 7756.5 4192.6 4195.6 4267.3 4384.6 4479.7 2908.5 3533.2 3576.9 3582.9 3586.3 658.4 668.4 670.6 674.7 675.9 522.6 574.1 646.7 661.7 670.8 8801.7 9313.8 9425.2 9457.1 9613.2 628.1 656.5 661.3 674.3 683.4 4434.5 4539.1 4552.7 4572.9 4638.3 78076.4 112889.0 117925.4 124934.8 125393.1 68956.0 68998.0 72441.0 91155.5 105983.0 92368.0 94976.0 95525.5 97294.0 97455.0 8561.2 8842.7 8885.2 8937.5 9094.3 56609.4 57630.0 58176.4 58562.7 61202.7 53051.0 53825.7 73695.0 74373.6 77335.5 12203.0 12669.0 12828.6 12859.7 12918.5 13806.6 13806.6 13996.4 14256.4 14363.4 12465.1 13191.4 13360.5 13485.1 13853.9 12733.6 13363.5 13367.5 13427.8 13453.3 9002.5 10022.6 12156.8 12239.4 12349.4 10683.3 10902.0 11659.1 11771.6 11927.5 9894.5 10636.8 11386.9 11614.7 11626.9 09:17:53 INFO - PROCESS | 2205 | -------- Summary: end -------- 09:17:53 INFO - PROCESS | 2205 | 09:17:54 INFO - TEST-INFO | 2205: exit 0 09:17:54 INFO - TEST-OK | dromaeo_css | took 1118927ms 09:17:54 INFO - TEST-START | kraken 09:17:54 INFO - operating with platform_type : mac_ 09:17:54 INFO - Initialising browser for kraken test... 09:17:54 INFO - TEST-INFO | started process 2207 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmp67T3_D/profile http://localhost:53909/getInfo.html) 09:17:56 ERROR - PROCESS | 2207 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 09:17:56 INFO - PROCESS | 2207 | __metrics Screen width/height:1600/1200 09:17:56 INFO - PROCESS | 2207 | colorDepth:24 09:17:56 INFO - PROCESS | 2207 | Browser inner width/height: 1024/689 09:17:56 INFO - PROCESS | 2207 | __metrics 09:17:57 INFO - PROCESS | 2207 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:17:57 INFO - PROCESS | 2207 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:17:57 INFO - TEST-INFO | 2207: exit 0 09:17:57 INFO - Browser initialized. 09:17:57 INFO - Running cycle 1/1 for kraken test... 09:17:57 INFO - TEST-INFO | started process 2208 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmp67T3_D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:18:09 INFO - PROCESS | 2208 | RSS: Main: 218320896 09:18:09 INFO - PROCESS | 2208 | 09:18:12 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:53909/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53909/tests/kraken/driver.html?testName=audio-beat-detection) 09:18:13 INFO - PROCESS | 2208 | RSS: Main: 268693504 09:18:13 INFO - PROCESS | 2208 | 09:18:16 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:53909/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53909/tests/kraken/driver.html?testName=audio-dft) 09:18:16 INFO - PROCESS | 2208 | RSS: Main: 279056384 09:18:16 INFO - PROCESS | 2208 | 09:18:19 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:53909/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53909/tests/kraken/driver.html?testName=audio-fft) 09:18:20 INFO - PROCESS | 2208 | RSS: Main: 376545280 09:18:20 INFO - PROCESS | 2208 | 09:18:22 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:53909/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53909/tests/kraken/driver.html?testName=audio-oscillator) 09:18:23 INFO - PROCESS | 2208 | RSS: Main: 278827008 09:18:23 INFO - PROCESS | 2208 | 09:18:25 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:53909/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53909/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:18:25 INFO - PROCESS | 2208 | RSS: Main: 279953408 09:18:25 INFO - PROCESS | 2208 | 09:18:28 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:53909/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53909/tests/kraken/driver.html?testName=imaging-darkroom) 09:18:29 INFO - PROCESS | 2208 | RSS: Main: 320012288 09:18:29 INFO - PROCESS | 2208 | 09:18:32 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:53909/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53909/tests/kraken/driver.html?testName=imaging-desaturate) 09:18:32 INFO - PROCESS | 2208 | RSS: Main: 320368640 09:18:32 INFO - PROCESS | 2208 | 09:18:35 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:53909/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53909/tests/kraken/driver.html?testName=json-parse-financial) 09:18:35 INFO - PROCESS | 2208 | RSS: Main: 318222336 09:18:35 INFO - PROCESS | 2208 | 09:18:37 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:53909/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53909/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:18:38 INFO - PROCESS | 2208 | RSS: Main: 296542208 09:18:38 INFO - PROCESS | 2208 | 09:18:40 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:53909/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53909/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:18:40 INFO - PROCESS | 2208 | RSS: Main: 296882176 09:18:40 INFO - PROCESS | 2208 | 09:18:42 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:53909/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53909/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:18:43 INFO - PROCESS | 2208 | RSS: Main: 299577344 09:18:43 INFO - PROCESS | 2208 | 09:18:46 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:53909/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53909/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:18:47 INFO - PROCESS | 2208 | RSS: Main: 317321216 09:18:47 INFO - PROCESS | 2208 | 09:18:50 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:53909/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53909/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:18:50 INFO - PROCESS | 2208 | RSS: Main: 310538240 09:18:50 INFO - PROCESS | 2208 | 09:18:52 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:53909/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53909/tests/kraken/driver.html?testName=ai-astar) 09:18:53 INFO - PROCESS | 2208 | RSS: Main: 354525184 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | __start_tp_report 09:18:53 INFO - PROCESS | 2208 | _x_x_mozilla_page_load 09:18:53 INFO - PROCESS | 2208 | _x_x_mozilla_page_load_details 09:18:53 INFO - PROCESS | 2208 | |i|pagename|runs| 09:18:53 INFO - PROCESS | 2208 | |0;ai-astar;116;113;115;113;114;113;114;114;115;114 09:18:53 INFO - PROCESS | 2208 | |1;audio-beat-detection;149;113;104;169;142;135;113;126;146;111 09:18:53 INFO - PROCESS | 2208 | |2;audio-dft;188;179;186;183;183;184;183;183;187;182 09:18:53 INFO - PROCESS | 2208 | |3;audio-fft;81;59;59;87;60;60;58;58;82;57 09:18:53 INFO - PROCESS | 2208 | |4;audio-oscillator;88;70;96;69;68;69;89;68;67;92 09:18:53 INFO - PROCESS | 2208 | |5;imaging-gaussian-blur;86;83;86;84;89;120;131;117;88;84 09:18:53 INFO - PROCESS | 2208 | |6;imaging-darkroom;94;94;93;93;93;93;96;92;93;101 09:18:53 INFO - PROCESS | 2208 | |7;imaging-desaturate;82;81;80;79;80;83;84;85;81;82 09:18:53 INFO - PROCESS | 2208 | |8;json-parse-financial;56;55;55;54;54;58;57;56;57;57 09:18:53 INFO - PROCESS | 2208 | |9;json-stringify-tinderbox;68;77;76;75;71;68;72;69;69;70 09:18:53 INFO - PROCESS | 2208 | |10;stanford-crypto-aes;86;85;85;84;84;84;85;84;84;86 09:18:53 INFO - PROCESS | 2208 | |11;stanford-crypto-ccm;151;147;149;163;158;148;158;157;211;194 09:18:53 INFO - PROCESS | 2208 | |12;stanford-crypto-pbkdf2;142;201;228;167;167;169;167;169;167;168 09:18:53 INFO - PROCESS | 2208 | |13;stanford-crypto-sha256-iterative;66;67;72;86;87;87;70;58;81;83 09:18:53 INFO - PROCESS | 2208 | __end_tp_report 09:18:53 INFO - PROCESS | 2208 | __start_cc_report 09:18:53 INFO - PROCESS | 2208 | _x_x_mozilla_cycle_collect,2085 09:18:53 INFO - PROCESS | 2208 | __end_cc_report 09:18:53 INFO - PROCESS | 2208 | __startTimestamp1479835133009__endTimestamp 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | ------- Summary: start ------- 09:18:53 INFO - PROCESS | 2208 | Number of tests: 14 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.00 stddev:0.99 (0.9%) stddev-sans-first:0.78 09:18:53 INFO - PROCESS | 2208 | Values: 116.0 113.0 115.0 113.0 114.0 113.0 114.0 114.0 115.0 114.0 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | [#1] audio-beat-detection Cycles:10 Average:130.80 Median:138.50 stddev:20.90 (15.1%) stddev-sans-first:21.11 09:18:53 INFO - PROCESS | 2208 | Values: 149.0 113.0 104.0 169.0 142.0 135.0 113.0 126.0 146.0 111.0 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | [#2] audio-dft Cycles:10 Average:183.80 Median:183.50 stddev:2.62 (1.4%) stddev-sans-first:2.29 09:18:53 INFO - PROCESS | 2208 | Values: 188.0 179.0 186.0 183.0 183.0 184.0 183.0 183.0 187.0 182.0 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | [#3] audio-fft Cycles:10 Average:66.10 Median:60.00 stddev:12.02 (20.0%) stddev-sans-first:11.48 09:18:53 INFO - PROCESS | 2208 | Values: 81.0 59.0 59.0 87.0 60.0 60.0 58.0 58.0 82.0 57.0 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:79.00 stddev:11.96 (15.1%) stddev-sans-first:12.07 09:18:53 INFO - PROCESS | 2208 | Values: 88.0 70.0 96.0 69.0 68.0 69.0 89.0 68.0 67.0 92.0 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | [#5] imaging-gaussian-blur Cycles:10 Average:96.80 Median:85.00 stddev:18.27 (21.5%) stddev-sans-first:18.96 09:18:53 INFO - PROCESS | 2208 | Values: 86.0 83.0 86.0 84.0 89.0 120.0 131.0 117.0 88.0 84.0 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:93.00 stddev:2.62 (2.8%) stddev-sans-first:2.77 09:18:53 INFO - PROCESS | 2208 | Values: 94.0 94.0 93.0 93.0 93.0 93.0 96.0 92.0 93.0 101.0 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | [#7] imaging-desaturate Cycles:10 Average:81.70 Median:82.00 stddev:1.89 (2.3%) stddev-sans-first:2.00 09:18:53 INFO - PROCESS | 2208 | Values: 82.0 81.0 80.0 79.0 80.0 83.0 84.0 85.0 81.0 82.0 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.50 stddev:1.37 (2.4%) stddev-sans-first:1.45 09:18:53 INFO - PROCESS | 2208 | Values: 56.0 55.0 55.0 54.0 54.0 58.0 57.0 56.0 57.0 57.0 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.50 stddev:3.37 (4.7%) stddev-sans-first:3.33 09:18:53 INFO - PROCESS | 2208 | Values: 68.0 77.0 76.0 75.0 71.0 68.0 72.0 69.0 69.0 70.0 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:85.00 stddev:0.82 (1.0%) stddev-sans-first:0.73 09:18:53 INFO - PROCESS | 2208 | Values: 86.0 85.0 85.0 84.0 84.0 84.0 85.0 84.0 84.0 86.0 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | [#11] stanford-crypto-ccm Cycles:10 Average:163.60 Median:158.00 stddev:21.52 (13.6%) stddev-sans-first:22.34 09:18:53 INFO - PROCESS | 2208 | Values: 151.0 147.0 149.0 163.0 158.0 148.0 158.0 157.0 211.0 194.0 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.50 Median:168.50 stddev:23.45 (13.9%) stddev-sans-first:21.72 09:18:53 INFO - PROCESS | 2208 | Values: 142.0 201.0 228.0 167.0 167.0 169.0 167.0 169.0 167.0 168.0 09:18:53 INFO - PROCESS | 2208 | 09:18:53 INFO - PROCESS | 2208 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.70 Median:82.00 stddev:10.39 (12.7%) stddev-sans-first:10.41 09:18:53 INFO - PROCESS | 2208 | Values: 66.0 67.0 72.0 86.0 87.0 87.0 70.0 58.0 81.0 83.0 09:18:53 INFO - PROCESS | 2208 | -------- Summary: end -------- 09:18:53 INFO - PROCESS | 2208 | 09:18:54 INFO - TEST-INFO | 2208: exit 0 09:18:54 INFO - TEST-OK | kraken | took 59608ms 09:18:54 INFO - SUITE-END | took 1178s 09:18:54 INFO - Completed test suite (00:19:38) 09:18:54 INFO - javascript benchmark 09:18:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2761.5305398082323, 3312.0891754767445, 3351.4972754086884, 3371.6459771723953, 3399.592048954126, 5696.344920334162, 5737.684427356494, 5750.539956803456, 5750.856228594282, 5796.101604251337, 5674.205611214423, 5689.8577535561635, 5782.653040817554, 5790.565707571938, 5811.796587119452, 5235.1885457754015, 5241.764120614563, 5247.763850626712, 5260.579153667697, 5299.072662284085, 5164.457731938149, 5174.767135478894, 5196.974015129938, 5314.335708036496, 5329.333833270841, 5172.120739474299, 5235.345581802275, 5253.868653283679, 5255.395629502599, 5307.124324486455, 5240.318758561362, 5255.369355677305, 5317.0, 5334.466553344634, 5337.01265265927, 5191.792328306863, 5232.686038837682, 5264.578833693296, 5288.1538953767595, 5312.043832110218, 5577.442255774431, 5633.070543360341, 5638.915416268759, 5658.603897727161, 5669.0079236133515, 6175.691215439255, 6253.749850005995, 6273.466755325803, 6288.088227207029, 6288.434040936292, 6303.149074874879, 6340.270868850095, 6367.108604795332, 6384.074309225136, 6388.456981156607, 5709.400512946164, 5741.799037033684, 5834.970825145932, 5869.031609784381, 5966.254218222723, 6296.055591661196, 6315.431611155018, 6333.809985700436, 6337.2395312562785, 6338.112664227012, 6221.471174950089, 6237.438630523275, 6238.282597501255, 6238.812835614848, 6260.843478912972, 5882.1470886722045, 5930.525557955353, 5971.970140149271, 6186.81439556814, 6346.777862774781, 6143.204841796972, 6210.7515699371515, 6223.96888015557, 6224.848403045451, 6231.158793562811, 6192.195014648067, 6226.59527130735, 6234.719437625317, 6245.531585131133, 6255.937440625627, 4766.642501812308, 4905.5094490550655, 4915.5330243626795, 4917.1149193144865, 4945.455999840015, 4429.15845989261, 4446.688731788809, 4454.398656181439, 4471.19518486669, 4519.502854685982, 4854.951450485521, 5713.600047996683, 5745.253117094751, 5753.884922301614, 5756.798512052058, 6340.239171299381, 6346.270178929482, 6350.872982540416, 6371.96814015927, 6378.075179099004, 5564.693941833238, 5674.687892165971, 5695.231143795618, 5696.401877802854, 5759.2512973313205, 5410.729463526808, 5428.375736790241, 5431.0, 5437.347518297751, 5461.481159289861, 5402.270693456412, 6265.8120256392385, 6332.746690132435, 6349.0, 6364.77723279683, 6161.260648722003, 6239.313675495601, 6293.37066293367, 6334.429901308904, 6338.8732225354315, 6263.4362907338555, 6284.685765711788, 6308.684565771601, 6350.333215012357, 6371.522135839923, 6250.749970001149, 6252.968735156477, 6262.749490020348, 6277.654728989949, 6280.497560195265, 6179.876402471836, 6282.528810339151, 6286.214223222098, 6312.368763123651, 6352.714127864165, 5866.090755932837, 6128.540359472968, 6137.009281402767, 6156.26124865019, 6215.782447614311, 6199.9770037943235, 6200.596961197508, 6204.503639708722, 6210.875782484415, 6220.973539365954, 5861.208736820475, 5933.703314834327, 6029.8191054268445, 6071.5446341523675, 6081.787137450168, 5918.112283157561, 6014.0377539593455, 6017.849553761191, 6025.457708806229, 6051.152838602511, 5890.852728681646, 5915.08316210988, 5917.378675239038, 5922.1412895130825, 5923.585349025527], "value": 5714.695408044604, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.7903824721505, 454.77943197549183, 486.71770373183557, 506.2077848167615, 518.7587771686165, 31033.534496982564, 31510.68489315106, 31523.527147092813, 31624.84187579062, 38234.85295441141, 38408.655697050606, 40524.39213411801, 40535.78392648218, 40544.79727601358, 40869.0, 9376.156145946881, 9543.0, 9564.473953932531, 9572.904270957271, 9576.952115239414, 8355.749327520183, 8505.659773609048, 9396.0, 9494.762630934212, 10196.439195844225, 11241.100711943065, 11418.086553075735, 11425.200235983484, 11433.771324573503, 11520.539178432853, 11402.030827379682, 11476.196666233367, 11476.71308217293, 11477.655670329903, 11486.483108260065, 8382.580870956488, 8618.956905215497, 11307.660770176908, 11377.601783937605, 11386.772264554662, 38439.423408648894, 39094.0, 39227.8038609808, 40216.59783402158, 40358.192836143404, 3241.773075884689, 3330.6835850594157, 3392.6607339266125, 3748.906277343061, 3782.583915769263, 28705.28236794076, 29535.0, 30504.77980880762, 30978.0, 32037.51943720846, 3222.1622378181605, 3241.2383089973837, 3246.1559994401628, 3268.313654132635, 3361.798292102482, 500246.0, 512925.0, 513758.0, 518852.0, 531056.3447182778, 51692.0, 63720.72558548896, 65553.67223163854, 65840.34159658437, 69247.96128058083, 66897.3310266891, 67840.66079669668, 67962.66018669875, 67985.66007170032, 67996.0, 16367.91816040912, 16544.917275413547, 16551.172441377883, 16553.586160346087, 16564.917175414288, 12506.374681265901, 12751.681207969876, 12798.936005319914, 12820.102592818414, 12829.23024618526, 15298.617534561503, 17536.123193840256, 17567.0, 18421.447356579323, 19441.833489990644, 7578.787394016931, 8038.397120216007, 8236.705317872778, 8260.230640862737, 8278.254957053894, 757.8257000889827, 895.901450840407, 978.3689520259535, 996.236661109846, 1097.9203334646393, 27420.862895685394, 29160.0, 29548.852255738584, 29610.40779184404, 29703.405931881673, 1048.7325731938306, 1054.5887104029434, 1114.8383484394717, 1163.203205804027, 1188.5542921404474, 14757.77863332051, 15234.619134521505, 15253.694926101638, 15256.0, 15276.38894444232, 685578.0, 696654.0, 700214.0, 700418.0, 700932.9906700868, 48437.03125937532, 49388.0, 49462.75268623706, 56823.0, 57354.71322643443, 1095.39205740813, 1233.0751936047964, 1257.126297223421, 1355.2681551962028, 1371.149887070023, 29487.0, 30582.235444114074, 30626.0, 30967.380652387277, 31238.687613123577, 47626.285605716635, 48117.75941120272, 48222.79443013812, 48356.75821620869, 48378.0, 597189.0, 601961.0, 604769.0, 604899.9510004844, 605767.9423205712, 26261.080862169732, 26688.332791680365, 27115.59326610063, 27126.0, 27871.581926271527, 45293.0, 45419.54580454285, 45778.77110614425, 45813.0, 45897.77051114723, 30365.240868978453, 36255.63744362522, 37262.88211353664, 37268.06829829276, 37705.81147094247, 36908.26183476369, 37611.43582846205, 38377.80811095927, 38760.0, 39258.0, 36387.0, 36904.0, 37011.6298837019, 37099.44350834793, 37241.44137837989, 27422.86288568624, 31843.20391990219, 33059.504107437926, 33301.83349083239, 34021.0, 24855.87572062128, 24859.875700621382, 25143.49713005693, 25246.50332308288, 25343.61984570196], "value": 19961.41040321302, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.6657809034783, 368.96863766579844, 370.65821725354203, 382.9100161462057, 402.9476168098147, 12369.196002259858, 15135.0, 15595.922020389897, 20502.897485512553, 21092.0, 17052.829471705278, 19697.310594129172, 20663.483412914647, 20822.47943801402, 21188.364349069554, 5589.385167631557, 6025.246844144482, 6036.5170786336985, 6066.998945174041, 6070.423309785585, 4683.8360657377, 6022.367651396606, 6057.878842423149, 6079.331273559905, 6096.298925623566, 5681.744321505522, 6011.5491338149595, 6028.487578555828, 6034.125051867477, 6037.72830222641, 5911.349751527328, 5919.378465261172, 5919.5560332974765, 5919.822405327847, 5944.405559444042, 4965.602751779849, 5080.568151707109, 5185.377754669426, 5202.583793296565, 5227.738613069369, 25396.23811285646, 25805.83873725687, 25857.612135817788, 25873.74126258751, 26219.606705899427, 1907.246637578149, 2211.2150186683657, 2401.1955994741784, 2420.213430635033, 2482.081629796987, 9823.017698230191, 9983.950080249624, 10372.222083343704, 12584.496620135184, 14750.778738318933, 1822.7083666613278, 1844.5480857190075, 1860.209411000333, 1877.586930875219, 1918.3381733301987, 113815.43092284485, 123670.14494782586, 126609.60513553824, 134050.32974835063, 135040.0, 11535.942320288512, 11790.705232369259, 11793.631319879523, 11793.941030294794, 11796.410179490991, 15137.091774493565, 15138.848611513964, 15169.24153792306, 15170.24148792578, 15179.165145916859, 8516.70191543293, 8655.047944726075, 8703.564821759013, 8857.557122143868, 8868.556572171366, 8587.441816282048, 8615.224629783224, 8623.741287761377, 8769.517676527852, 8772.29821614282, 4507.414036175276, 4593.517680643551, 4632.2125238709505, 4663.696859704109, 4682.555157260055, 1784.6073863749955, 1796.1680449626367, 1802.4592622213545, 1834.0, 1900.828925396721, 870.4907629036958, 942.7972985808084, 945.5272363818091, 955.0831202045974, 982.1553464020936, 20535.794642053686, 20546.17815287372, 21524.0314185862, 21568.784312156993, 21621.810800405816, 883.6907082521067, 931.7018554062635, 980.3823591137681, 980.4460479828874, 981.8478135888948, 10244.282900197064, 10931.125509959184, 11119.666410007712, 11150.49822757978, 11184.552617895193, 571072.0, 572889.0, 579891.0, 591190.0, 595777.0, 98786.0, 118145.0, 118695.0, 121775.0, 125790.0, 926.6200857648466, 937.7936853892132, 950.3015283766426, 950.9144177023962, 978.8972157923315, 15125.924370378078, 19468.61062778765, 20116.177926193977, 20205.292814751414, 20247.392578222676, 37678.811605941795, 55217.44782552123, 55440.72279638576, 57301.0, 57345.0, 67748.0, 75939.24060759542, 79589.0, 80252.0, 80956.59521702354, 7927.722529711433, 8167.469114507776, 8295.95852020736, 8524.573771311534, 8606.096359882122, 10098.444585547866, 12758.744825103631, 12937.547185848827, 13062.34688265564, 13239.735205296032, 11029.724256893642, 11302.604408845651, 11628.360440175871, 11630.767384652429, 11779.293242405483, 11007.83488247693, 11039.558417663204, 11129.721756956142, 11191.608293709682, 11586.826197607212, 11567.595134170264, 11665.0, 11707.0, 11811.704707382385, 11834.644960651194, 7069.151701795612, 8241.299489543457, 8367.748967530984, 8370.748877533684, 8376.581170941306, 8313.0, 9942.508623706502, 10506.737331566772, 10867.0762985144, 11492.885071149181], "value": 9545.46563729918, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.783732440134, 1470.9705805883882, 1612.435647523367, 1785.071762683404, 1804.9729254061192, 7280.235575264601, 8230.711925082616, 8663.39356245063, 8663.566821658924, 8667.60995755191, 7580.658870350833, 7809.960950195241, 7810.609469526529, 8216.95891520543, 8477.618507167177, 2040.6224848403092, 2248.9887550562225, 2290.75947025562, 2337.742848286687, 2339.543788961153, 2329.778671026258, 2358.2571489980687, 2362.4802543440414, 2374.0, 2378.203301893859, 2288.2677543186187, 2300.0914638717695, 2327.905884234407, 2333.6382860656627, 2344.15610380263, 1919.500929758259, 2327.755585663498, 2330.685357476736, 2331.778481044298, 2337.439014636492, 2147.8174355179826, 2224.6996655451467, 2319.489712263299, 2358.575456417844, 2360.44529535559, 8492.108328625529, 8533.061363250037, 8597.742067737978, 8605.0, 8622.13778622139, 5101.081805275049, 5297.417284098746, 5299.54953828925, 5307.840764777063, 5309.36287645485, 3230.720216642446, 3343.626025521882, 3386.221169131089, 3408.011676613779, 3411.77823441478, 1069.288922866293, 1078.665613659768, 1084.7004350776326, 1106.2035334559184, 1117.050507068993, 11243.60647377338, 11284.774304513945, 11425.31448113116, 11442.084633229239, 11507.136964727679, 4567.6802623816675, 4667.299905014207, 4774.9972505773485, 4778.5699287064335, 4846.709197448165, 9731.464769437749, 9735.610575577039, 9746.269029822792, 9748.0, 9756.02439756033, 5291.523762861361, 5309.442508536626, 5310.123829568078, 5313.07021271276, 5327.680339179662, 4056.4320995060125, 4158.979205103955, 4229.2598795210715, 4871.269309603517, 4871.610271178368, 3846.769193848322, 3865.130345672201, 3907.1208977980295, 3954.0312623406876, 3956.1098752780968, 1249.1755441408627, 1259.3577275589516, 1262.741138066694, 1267.9112462127748, 1272.204871955028, 851.7700220940311, 898.4115404410122, 899.3974037394961, 904.3533873280636, 934.8688087414305, 5795.797147099831, 5818.679972601548, 5823.941760582425, 5978.701064946736, 6123.295820980555, 440.0626665203097, 442.4911351945224, 444.2026562320642, 450.9954900450953, 451.68156449702883, 3772.1135533149686, 3901.4147877818555, 3911.9022024449046, 3942.388929715841, 3982.2831890259454, 9982.60069597208, 10132.493375331203, 10139.543720532594, 10537.84193237102, 10555.419451930064, 9971.501424928869, 10084.899151008543, 10228.488575571191, 10444.268901176845, 10522.73693157662, 7988.84022319562, 8020.518768873886, 8082.919170808216, 8171.877421838677, 8173.386995975326, 5743.827685169452, 5798.768049277982, 5845.883082338244, 5869.647821130746, 5880.411958804086, 8081.9191808083515, 9143.222826059859, 9282.443053416546, 9603.90396096058, 9640.662576809778, 9544.475053872104, 9680.27397945143, 9905.959874213391, 9921.206303495559, 9925.553350099119, 6700.765473208415, 6933.687984040831, 7144.785656430316, 7338.596377199306, 7440.330370266702, 7995.040595128622, 8048.39637027237, 8183.345332373515, 8204.589770511331, 8219.095899451175, 7019.684114214975, 7084.2915708428745, 7115.893261601183, 7257.782266532013, 7356.301151390609, 7050.506464547432, 7059.223485416703, 7100.112485939258, 7128.643567821485, 7264.67308971087, 6719.529632925648, 6873.1064961554675, 6878.518503704692, 6881.380675739208, 6910.378065974087, 4168.931486902279, 4195.307774217342, 4856.975715121402, 4879.316895634543, 5093.962415034016, 4959.107360675113, 4967.677100988424, 5003.124453220745, 5027.773750181177, 5087.720175390389], "value": 4388.095756209767, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [257.66117555414627, 309.4430025953285, 310.18421551320023, 314.9039542939403, 318.5460718476174, 5312.096943519601, 5571.442855714432, 5592.7762889484475, 5608.775648974041, 5623.831285061445, 4617.515160908099, 5398.406175320712, 5469.425710300421, 5473.972630136854, 5516.99999999999, 3881.2625601135833, 4062.573429789874, 4072.165206132736, 4081.428599996003, 4087.591240875918, 3691.7969511676847, 3742.494763206973, 3892.70804689648, 4040.070783719746, 4145.854895078673, 3764.9623503764888, 4077.3068578341754, 4086.3053280942313, 4107.377585853586, 4111.691623128262, 3543.2559162575762, 3563.0914116900153, 3690.833912473945, 4124.4019617155345, 4163.938195760062, 3248.772585918987, 3262.075375528426, 3371.7302615790923, 3432.2963792422493, 3601.3877640801124, 4274.418465167908, 4454.265046267395, 4855.0, 5238.476152384769, 5307.230451584537, 4190.580941905816, 4474.239379305526, 4786.282057691339, 4800.663953523254, 4816.662833601649, 4979.8008079676765, 5237.633365664405, 5347.075052980924, 5704.372519022904, 5813.505852002585, 3451.9654803451895, 3988.3419235826345, 3990.670122916639, 4051.3112770828734, 4054.0, 5710.828675139753, 5926.940730592725, 5929.318128415244, 5966.8961242170335, 6012.428819262163, 3595.3168897909313, 3755.4179102239195, 3846.6922646188236, 3989.7406668567032, 4066.532348779869, 3485.7734247273847, 3765.4916586260506, 3778.1876896467393, 4154.979225103855, 4163.542010378856, 4043.4137050128156, 4611.953880461219, 4743.478217396084, 4806.759662016955, 4828.782704778293, 4781.665283430196, 5722.971385143048, 5755.539556835443, 5783.508401785811, 5820.605442890591, 1178.950733846877, 1256.4984843112393, 1335.8396992360786, 1336.8061631063442, 1368.2406264523286, 759.343168159538, 814.0882211922684, 864.9870251946226, 939.8778158839444, 939.9812003760023, 4577.404937358122, 5107.821226257063, 5144.536991670768, 5145.706747681487, 5147.742612869416, 4674.859754207379, 5565.493738944439, 5597.972010139923, 5650.463205995424, 5697.857553561112, 4638.466576343696, 5053.36832895888, 5116.692998420032, 5132.28148059272, 5165.457626949116, 2904.840233787162, 2907.6074729911616, 2910.592517047615, 2927.6486821581548, 2938.6032885560176, 5164.948350516447, 5240.68555886648, 5719.056355701346, 5719.7712091516705, 5773.391630231105, 5150.639455238097, 5355.33106886058, 5596.552275817925, 5693.430656934356, 5747.770089196385, 4697.050723949591, 4998.700077995332, 5711.343195532567, 5720.34110108064, 5814.883702325845, 5074.517920797518, 5127.743612819419, 5516.11114164505, 5627.577931655224, 5652.63257888236, 5413.458654134555, 5935.842510710543, 5945.375735547878, 5978.730957106854, 5992.970035149797, 5406.648567843077, 5879.73541190656, 5879.853003674943, 5975.267172520094, 6229.501639868709, 3350.5811773528308, 4008.9599104008585, 4016.256992456405, 4016.49793775778, 4042.8584999524874, 5853.209816674865, 6020.337762846171, 6116.318012546585, 6122.418370254819, 6140.662263575547, 5822.650640961556, 5825.679587622722, 5862.824115276549, 5876.147958546072, 5881.9705901470215, 5102.846914592568, 5809.518572763918, 5882.470577648032, 5883.911741323969, 5899.941000590111, 4990.575801056805, 5250.842474725764, 5303.0719624066405, 5757.97121014409, 5771.971140144273, 4876.634252431011, 5336.332958380202, 5812.244408226903, 5840.853978650398, 5858.451573565512, 5266.578673706171, 5733.928910473611, 5749.080147176432, 5764.625299355528, 5785.82642520725], "value": 4123.923343904173, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.2068607328168, 697.3479796390372, 744.1330849560262, 768.9000429944107, 775.0699161006789, 14197.858021419783, 14642.633934151638, 15896.602584935383, 16431.4249001285, 16716.41492547761, 14790.112593244412, 15120.621984450394, 15254.466093686724, 15295.617609559766, 15306.928515003954, 810.2059981218409, 835.3943391041507, 837.4431003382743, 837.7528629054443, 840.756180707594, 643.8519140597665, 645.9450946669539, 664.7939138866942, 814.1614137438448, 824.3858325547476, 852.4246133859651, 858.0132847225678, 858.0518527027638, 858.4677499950039, 858.8196478739472, 833.3125171733344, 854.8589482735341, 854.9444286121383, 855.70050482331, 857.1471385970937, 502.15888386952076, 513.0867056639188, 517.2938938349164, 635.0125554762343, 636.9044643303541, 12449.875501244962, 12488.0, 12542.933850622709, 14280.357383917748, 14821.925890370587, 13177.86822131776, 13461.125026873222, 13542.932285338511, 13566.932165339209, 13586.796198057036, 11161.665150045512, 11251.381174035425, 11495.310281383145, 11502.597409090642, 11543.134264930164, 3719.5722491913507, 3729.1422972716155, 3750.887473375803, 3802.372608519591, 3820.6943444524372, 90101.0, 95528.0, 96175.51912240394, 97862.51068744682, 98504.0, 10113.69658910234, 10340.638077667245, 10432.582696692047, 10539.209559283085, 10823.404712740874, 19151.52121196981, 19870.205191792167, 20003.199872005247, 20036.599268014557, 20112.79887201109, 7634.312911837961, 7645.350145237717, 7705.0, 7721.343685786771, 7756.53460792343, 4192.559781222928, 4195.559466255999, 4267.274563324242, 4384.583464570861, 4479.664025198123, 2908.520094184436, 3533.2050288685355, 3576.856925722942, 3582.874599389009, 3586.318599466093, 658.4337469775988, 668.4084585142184, 670.637855558003, 674.7065026713395, 675.9121314229119, 522.5532169994676, 574.0556784090148, 646.6863571167979, 661.6856992928417, 670.7719375412383, 8801.694083107304, 9313.813723725487, 9425.198858097001, 9457.148856602802, 9613.182879455184, 628.0986783965025, 656.5273003437518, 661.2692974263466, 674.3324109132022, 683.4122654517112, 4434.5343738907595, 4539.114872599877, 4552.726836389827, 4572.9314060289125, 4638.304254361873, 78076.43847123026, 112889.0, 117925.41037294759, 124934.75065249413, 125393.11910321352, 68956.0, 68998.0, 72441.0, 91155.53066816012, 105983.0, 92368.0, 94976.0, 95525.5223723877, 97294.0270597285, 97454.99999999859, 8561.1866872647, 8842.734717958472, 8885.222955408815, 8937.508437036025, 9094.317926155432, 56609.433905660415, 57630.0, 58176.4182358171, 58562.7071864638, 61202.69398652978, 53051.0, 53825.73087134539, 73695.0, 74373.62813185899, 77335.453290935, 12203.023758099509, 12669.0, 12828.55100071493, 12859.678508037374, 12918.483260669467, 13806.553423352309, 13806.585802425943, 13996.440142394598, 14256.358463869357, 14363.425462981364, 12465.127441079037, 13191.406386712813, 13360.532381366605, 13485.123466974615, 13853.930730346283, 12733.61799146027, 13363.465461381436, 13367.465301387836, 13427.798583021067, 13453.260070695815, 9002.549872506217, 10022.599096036078, 12156.75686486283, 12239.449224785085, 12349.3825308736, 10683.30558513694, 10902.0, 11659.06727461784, 11771.587994420153, 11927.522899083939, 9894.45580493051, 10636.787264254826, 11386.918242766924, 11614.70963225926, 11626.88373116258], "value": 7286.407051703689, "unit": "score"}], "name": "dromaeo_css", "value": 7236.381059657466, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 113.0, 115.0, 113.0, 114.0, 113.0, 114.0, 114.0, 115.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 113.0, 104.0, 169.0, 142.0, 135.0, 113.0, 126.0, 146.0, 111.0], "value": 130.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 179.0, 186.0, 183.0, 183.0, 184.0, 183.0, 183.0, 187.0, 182.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 87.0, 60.0, 60.0, 58.0, 58.0, 82.0, 57.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 96.0, 69.0, 68.0, 69.0, 89.0, 68.0, 67.0, 92.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 84.0, 89.0, 120.0, 131.0, 117.0, 88.0, 84.0], "value": 96.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 93.0, 93.0, 93.0, 96.0, 92.0, 93.0, 101.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 80.0, 79.0, 80.0, 83.0, 84.0, 85.0, 81.0, 82.0], "value": 81.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 54.0, 54.0, 58.0, 57.0, 56.0, 57.0, 57.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 77.0, 76.0, 75.0, 71.0, 68.0, 72.0, 69.0, 69.0, 70.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 84.0, 84.0, 84.0, 85.0, 84.0, 84.0, 86.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 147.0, 149.0, 163.0, 158.0, 148.0, 158.0, 157.0, 211.0, 194.0], "value": 163.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 201.0, 228.0, 167.0, 167.0, 169.0, 167.0, 169.0, 167.0, 168.0], "value": 174.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 72.0, 86.0, 87.0, 87.0, 70.0, 58.0, 81.0, 83.0], "value": 75.7, "unit": "score"}], "name": "kraken", "value": 1471.0, "alertThreshold": 2.0}]} 09:18:54 INFO - Return code: 0 09:18:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:18:54 ERROR - # TBPL SUCCESS # 09:18:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:18:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:18:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:18:54 INFO - 2016-11-22 09:18:54.334 screenresolution[2212:20879] starting screenresolution argv=screenresolution get 09:18:54 INFO - 2016-11-22 09:18:54.336 screenresolution[2212:20879] Display 0: 1600x1200x32@60 09:18:54 INFO - 2016-11-22 09:18:54.343 screenresolution[2213:20883] starting screenresolution argv=screenresolution list 09:18:54 INFO - Available Modes on Display 0 09:18:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:18:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:18:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:18:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:18:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:18:54 INFO - Intel Iris: 09:18:54 INFO - Chipset Model: Intel Iris 09:18:54 INFO - Type: GPU 09:18:54 INFO - Bus: Built-In 09:18:54 INFO - VRAM (Dynamic, Max): 1536 MB 09:18:54 INFO - Vendor: Intel (0x8086) 09:18:54 INFO - Device ID: 0x0a2e 09:18:54 INFO - Revision ID: 0x0009 09:18:54 INFO - Displays: 09:18:54 INFO - Display: 09:18:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:18:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:18:54 INFO - Display Serial Number: 1600x1200 60 09:18:54 INFO - Main Display: Yes 09:18:54 INFO - Mirror: Off 09:18:54 INFO - Online: Yes 09:18:54 INFO - Rotation: Supported 09:18:54 INFO - Return code: 0 09:18:54 INFO - Running post-action listener: _resource_record_post_action 09:18:54 INFO - [mozharness: 2016-11-22 17:18:54.590876Z] Finished run-tests step (success) 09:18:54 INFO - Running post-run listener: _resource_record_post_run 09:18:54 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 638976; Write bytes: 653081088; Read time: 6; Write time: 1889 09:18:54 INFO - TinderboxPrint: CPU usage
53.5% 09:18:54 INFO - TinderboxPrint: I/O read bytes / time
638,976 / 6 09:18:54 INFO - TinderboxPrint: I/O write bytes / time
653,081,088 / 1,889 09:18:54 INFO - TinderboxPrint: CPU idle
2,254.2 (46.5%) 09:18:54 INFO - TinderboxPrint: CPU system
972.0 (20.1%) 09:18:54 INFO - TinderboxPrint: CPU user
1,619.5 (33.4%) 09:18:54 INFO - TinderboxPrint: Swap in / out
353,042,432 / 0 09:18:54 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344154624; Write bytes: 347211776; Read time: 26547; Write time: 744 09:18:54 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 638976; Write bytes: 295846400; Read time: 6; Write time: 1110 09:18:55 INFO - Running post-run listener: _upload_blobber_files 09:18:55 INFO - Blob upload gear active. 09:18:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:18:55 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 09:18:55 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'] 09:18:55 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 09:18:55 INFO - (blobuploader) - INFO - Open directory for files ... 09:18:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:18:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:18:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:18:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:18:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:18:56 INFO - (blobuploader) - INFO - Done attempting. 09:18:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:18:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:18:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:18:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:18:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:18:57 INFO - (blobuploader) - INFO - Done attempting. 09:18:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:18:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:18:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:18:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:18:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:18:59 INFO - (blobuploader) - INFO - Done attempting. 09:18:59 INFO - (blobuploader) - INFO - Iteration through files over. 09:18:59 INFO - Return code: 0 09:18:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:18:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:18:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a30e6df8b71adf5baa33aa9641998bc6bb65b4fe00d8aa344df2f51383e31bd5386d04cb31555805ada99c6563719aeea7c83beaa0a624b04da80df84402c510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/79b4d0997096c236472ce05c6937f6346c32e576f3ab6826272ddfa4c0dac9f5a09eb76e296d5b03b6d507ca4d59f32d22d6c6baa73491636b08653882b0d0cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9c7ddb0c5e52444f051d712a3926e3f6d69e7c3be1c9919a56e5f8d321db8f4b5ecf38e080b59213985a252c47fb53a538d14d07dcdc450bb367d547c15177c7"} 09:18:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:18:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:18:59 INFO - Contents: 09:18:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a30e6df8b71adf5baa33aa9641998bc6bb65b4fe00d8aa344df2f51383e31bd5386d04cb31555805ada99c6563719aeea7c83beaa0a624b04da80df84402c510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/79b4d0997096c236472ce05c6937f6346c32e576f3ab6826272ddfa4c0dac9f5a09eb76e296d5b03b6d507ca4d59f32d22d6c6baa73491636b08653882b0d0cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9c7ddb0c5e52444f051d712a3926e3f6d69e7c3be1c9919a56e5f8d321db8f4b5ecf38e080b59213985a252c47fb53a538d14d07dcdc450bb367d547c15177c7"} 09:18:59 INFO - Running post-run listener: copy_logs_to_upload_dir 09:18:59 INFO - Copying logs to upload dir... 09:18:59 INFO - mkdir: /builds/slave/test/build/upload/logs 09:18:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.236481 ========= master_lag: 1.19 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-22 09:19:00.857237) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 09:19:00.861965) ========= 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.RWI4iO7dKz/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.DfZIxxJtov/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a30e6df8b71adf5baa33aa9641998bc6bb65b4fe00d8aa344df2f51383e31bd5386d04cb31555805ada99c6563719aeea7c83beaa0a624b04da80df84402c510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/79b4d0997096c236472ce05c6937f6346c32e576f3ab6826272ddfa4c0dac9f5a09eb76e296d5b03b6d507ca4d59f32d22d6c6baa73491636b08653882b0d0cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9c7ddb0c5e52444f051d712a3926e3f6d69e7c3be1c9919a56e5f8d321db8f4b5ecf38e080b59213985a252c47fb53a538d14d07dcdc450bb367d547c15177c7"} build_url:https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011650 build_url: 'https://queue.taskcluster.net/v1/task/RZ216sabTM6A5ZXwe3mJKw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a30e6df8b71adf5baa33aa9641998bc6bb65b4fe00d8aa344df2f51383e31bd5386d04cb31555805ada99c6563719aeea7c83beaa0a624b04da80df84402c510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/79b4d0997096c236472ce05c6937f6346c32e576f3ab6826272ddfa4c0dac9f5a09eb76e296d5b03b6d507ca4d59f32d22d6c6baa73491636b08653882b0d0cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9c7ddb0c5e52444f051d712a3926e3f6d69e7c3be1c9919a56e5f8d321db8f4b5ecf38e080b59213985a252c47fb53a538d14d07dcdc450bb367d547c15177c7"}' ========= master_lag: 0.69 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 09:19:01.560923) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:19:01.561260) ========= 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.RWI4iO7dKz/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.DfZIxxJtov/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.005243 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:19:01.588906) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 09:19:01.589389) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 09:19:01.589870) ========= ========= Total master_lag: 1.97 =========