builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0233 starttime: 1479044409.64 results: success (0) buildid: 20161113030203 builduid: 84e81a3769dd4dc6b6c6e565bb8d40a8 revision: b37be3d705d929ee52280051d58cedc70a47626f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-13 05:40:09.640489) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-13 05:40:09.640924) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-13 05:40:09.641200) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-13 05:40:09.665299) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 05:40:09.665685) ========= 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.XLpMTWadOD/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.OGojxSPos2/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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-13 05:40:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 143M=0s 2016-11-13 05:40:09 (143 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148946 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 05:40:09.836369) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 05:40:09.836680) ========= 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.XLpMTWadOD/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.OGojxSPos2/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.019967 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 05:40:09.885774) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 05:40:09.886137) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b37be3d705d929ee52280051d58cedc70a47626f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev b37be3d705d929ee52280051d58cedc70a47626f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XLpMTWadOD/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.OGojxSPos2/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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-13 05:40:09,972 truncating revision to first 12 chars 2016-11-13 05:40:09,972 Setting DEBUG logging. 2016-11-13 05:40:09,972 attempt 1/10 2016-11-13 05:40:09,972 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b37be3d705d9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-13 05:40:10,592 unpacking tar archive at: mozilla-central-b37be3d705d9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.879842 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 05:40:10.788333) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-13 05:40:10.788654) ========= script_repo_revision: b37be3d705d929ee52280051d58cedc70a47626f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-13 05:40:10.789062) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-13 05:40:10.789327) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-13 05:40:10.808301) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-13 05:40:10.808815) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XLpMTWadOD/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.OGojxSPos2/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 05:40:11 INFO - MultiFileLogger online at 20161113 05:40:11 in /builds/slave/test 05:40:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:40:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:40:11 INFO - {'append_to_log': False, 05:40:11 INFO - 'base_work_dir': '/builds/slave/test', 05:40:11 INFO - 'blob_upload_branch': 'Firefox', 05:40:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:40:11 INFO - 'branch': 'Firefox', 05:40:11 INFO - 'buildbot_json_path': 'buildprops.json', 05:40:11 INFO - 'config_files': ('talos/mac_config.py',), 05:40:11 INFO - 'default_actions': ('clobber', 05:40:11 INFO - 'read-buildbot-config', 05:40:11 INFO - 'download-and-extract', 05:40:11 INFO - 'populate-webroot', 05:40:11 INFO - 'create-virtualenv', 05:40:11 INFO - 'install', 05:40:11 INFO - 'run-tests'), 05:40:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:40:11 INFO - 'download_minidump_stackwalk': True, 05:40:11 INFO - 'download_symbols': 'ondemand', 05:40:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:40:11 INFO - 'tooltool.py': '/tools/tooltool.py', 05:40:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:40:11 INFO - '/tools/misc-python/virtualenv.py')}, 05:40:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:40:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:40:11 INFO - 'installer_path': 'installer.exe', 05:40:11 INFO - 'log_level': 'info', 05:40:11 INFO - 'log_name': 'talos', 05:40:11 INFO - 'log_to_console': True, 05:40:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:40:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:40:11 INFO - 'opt_config_files': (), 05:40:11 INFO - 'pip_index': False, 05:40:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:40:11 INFO - 'cmd': ('bash', 05:40:11 INFO - '-c', 05:40:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:40:11 INFO - 'enabled': True, 05:40:11 INFO - 'halt_on_failure': False, 05:40:11 INFO - 'name': 'check_screen_resolution'},), 05:40:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:40:11 INFO - 'cmd': ('bash', 05:40:11 INFO - '-c', 05:40:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:40:11 INFO - 'enabled': True, 05:40:11 INFO - 'halt_on_failure': False, 05:40:11 INFO - 'name': 'check_screen_resolution'},), 05:40:11 INFO - 'run_cmd_checks_enabled': True, 05:40:11 INFO - 'sps_profile': False, 05:40:11 INFO - 'sps_profile_interval': 0, 05:40:11 INFO - 'suite': 'dromaeojs', 05:40:11 INFO - 'system_bits': '32', 05:40:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:40:11 INFO - 'title': 't-yosemite-r7-0233', 05:40:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:40:11 INFO - 'use_talos_json': True, 05:40:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:40:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:40:11 INFO - 'work_dir': 'build'} 05:40:11 INFO - [mozharness: 2016-11-13 13:40:11.011451Z] Running clobber step. 05:40:11 INFO - Running pre-action listener: _resource_record_pre_action 05:40:11 INFO - Running main action method: clobber 05:40:11 INFO - rmtree: /builds/slave/test/build 05:40:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:40:13 INFO - Running post-action listener: _resource_record_post_action 05:40:13 INFO - [mozharness: 2016-11-13 13:40:13.198282Z] Finished clobber step (success) 05:40:13 INFO - [mozharness: 2016-11-13 13:40:13.198413Z] Running read-buildbot-config step. 05:40:13 INFO - Running pre-action listener: _resource_record_pre_action 05:40:13 INFO - Running main action method: read_buildbot_config 05:40:13 INFO - Using buildbot properties: 05:40:13 INFO - { 05:40:13 INFO - "project": "", 05:40:13 INFO - "product": "firefox", 05:40:13 INFO - "script_repo_revision": "production", 05:40:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:40:13 INFO - "repository": "", 05:40:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:40:13 INFO - "buildid": "20161113030203", 05:40:13 INFO - "stage_platform": "macosx64", 05:40:13 INFO - "basedir": "/builds/slave/test", 05:40:13 INFO - "nightly_build": "True", 05:40:13 INFO - "buildnumber": 11, 05:40:13 INFO - "slavename": "t-yosemite-r7-0233", 05:40:13 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:40:13 INFO - "platform": "yosemite_r7", 05:40:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:40:13 INFO - "branch": "mozilla-central", 05:40:13 INFO - "repo_path": "mozilla-central", 05:40:13 INFO - "revision": "b37be3d705d929ee52280051d58cedc70a47626f", 05:40:13 INFO - "pgo_build": "False", 05:40:13 INFO - "builduid": "84e81a3769dd4dc6b6c6e565bb8d40a8", 05:40:13 INFO - "slavebuilddir": "test" 05:40:13 INFO - } 05:40:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:40:13 INFO - Running post-action listener: _resource_record_post_action 05:40:13 INFO - [mozharness: 2016-11-13 13:40:13.202070Z] Finished read-buildbot-config step (success) 05:40:13 INFO - [mozharness: 2016-11-13 13:40:13.202172Z] Running download-and-extract step. 05:40:13 INFO - Running pre-action listener: _resource_record_pre_action 05:40:13 INFO - Running main action method: download_and_extract 05:40:13 INFO - mkdir: /builds/slave/test/build/tests 05:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:13 INFO - trying https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:40:13 INFO - Downloading https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:40:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:40:14 INFO - Downloaded 1199 bytes. 05:40:14 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:40:14 INFO - Using the following test package requirements: 05:40:14 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:40:14 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:14 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:40:14 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:14 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:40:14 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:14 INFO - u'jsshell-mac.zip'], 05:40:14 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:14 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:40:14 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:40:14 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:14 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:40:14 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:14 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:40:14 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:14 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:40:14 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:14 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:40:14 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:40:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:40:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:40:14 INFO - Fetch https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:40:14 INFO - Http code: 200 05:40:14 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 05:40:14 INFO - x-amz-cf-id: 4_Zt0y7jfZbsppojeiwuyTxF82kDAhmcujpND1Rlnx_KFcP8nH3DjQ== 05:40:14 INFO - x-amz-version-id: kKghIzXxzi3Iw.8BH33nXicn4JySG8OE 05:40:15 INFO - Expected file size: 16334036 05:40:15 INFO - Obtained file size: 16334036 05:40:21 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:40:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:40:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:40:21 INFO - Fetch https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:40:22 INFO - Http code: 200 05:40:22 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 05:40:22 INFO - x-amz-cf-id: scPcGJ4A9qCbsSYCIfLVlLh6K7eQqoUXsL-nPr6tAmwRkTONEjpT0Q== 05:40:22 INFO - x-amz-version-id: kKghIzXxzi3Iw.8BH33nXicn4JySG8OE 05:40:22 INFO - Expected file size: 16334036 05:40:22 INFO - Obtained file size: 16334036 05:40:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:40:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:40:28 INFO - Fetch https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:40:30 INFO - Http code: 200 05:40:30 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 05:40:30 INFO - x-amz-cf-id: UTX_u7z-qkHLdDEbZ0fUPMwBFa4KaxnWOAjjBjV0PYmpSlkvYm5Chw== 05:40:30 INFO - x-amz-version-id: T12vAw7r7ptrY1wPH21l8FvnrM1ZQ4Ow 05:40:30 INFO - Expected file size: 14409038 05:40:30 INFO - Obtained file size: 14409038 05:40: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')]} 05:40:31 INFO - trying https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:40:31 INFO - Downloading https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:40:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:40:34 INFO - Downloaded 111216907 bytes. 05:40:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:40:34 INFO - mkdir: /builds/slave/test/properties 05:40:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:40:34 INFO - Writing to file /builds/slave/test/properties/build_url 05:40:34 INFO - Contents: 05:40:34 INFO - build_url:https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:40:35 INFO - Running post-action listener: _resource_record_post_action 05:40:35 INFO - Running post-action listener: set_extra_try_arguments 05:40:35 INFO - [mozharness: 2016-11-13 13:40:35.107143Z] Finished download-and-extract step (success) 05:40:35 INFO - [mozharness: 2016-11-13 13:40:35.107277Z] Running populate-webroot step. 05:40:35 INFO - Running pre-action listener: _resource_record_pre_action 05:40:35 INFO - Running main action method: populate_webroot 05:40:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:40:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:40:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:40:35 INFO - '--tppagecycles', 05:40:35 INFO - '7'], 05:40:35 INFO - 'tests': ['tsvgm']}}, 05:40:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:40:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:40:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:40:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:40:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:40:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:40:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:35 INFO - 'talos_options': ['--disable-e10s'], 05:40:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:35 INFO - 'talos_options': ['--disable-e10s'], 05:40:35 INFO - 'tests': ['damp', 'tps']}, 05:40:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:35 INFO - 'tests': ['damp', 'tps']}, 05:40:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:40:35 INFO - 'tests': ['dromaeo_dom']}, 05:40:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:40:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:40:35 INFO - 'tests': ['basic_compositor_video']}, 05:40:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:40:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:40:35 INFO - 'tests': ['a11yr', 05:40:35 INFO - 'ts_paint', 05:40:35 INFO - 'tpaint', 05:40:35 INFO - 'sessionrestore', 05:40:35 INFO - 'sessionrestore_no_auto_restore', 05:40:35 INFO - 'tabpaint']}, 05:40:35 INFO - 'other-e10s': {'tests': ['a11yr', 05:40:35 INFO - 'ts_paint', 05:40:35 INFO - 'tpaint', 05:40:35 INFO - 'sessionrestore', 05:40:35 INFO - 'sessionrestore_no_auto_restore', 05:40:35 INFO - 'tabpaint']}, 05:40:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:40:35 INFO - 'ts_paint', 05:40:35 INFO - 'tpaint', 05:40:35 INFO - 'sessionrestore', 05:40:35 INFO - 'sessionrestore_no_auto_restore', 05:40:35 INFO - 'tabpaint']}, 05:40:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:40:35 INFO - 'ts_paint', 05:40:35 INFO - 'tpaint', 05:40:35 INFO - 'sessionrestore', 05:40:35 INFO - 'sessionrestore_no_auto_restore', 05:40:35 INFO - 'tabpaint']}, 05:40:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:40:35 INFO - 'tests': ['a11yr', 05:40:35 INFO - 'ts_paint', 05:40:35 INFO - 'tpaint', 05:40:35 INFO - 'sessionrestore', 05:40:35 INFO - 'sessionrestore_no_auto_restore', 05:40:35 INFO - 'tabpaint']}, 05:40:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:40:35 INFO - 'tests': ['a11yr', 05:40:35 INFO - 'ts_paint', 05:40:35 INFO - 'tpaint', 05:40:35 INFO - 'sessionrestore', 05:40:35 INFO - 'sessionrestore_no_auto_restore', 05:40:35 INFO - 'tabpaint']}, 05:40:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:40:35 INFO - 'tests': ['tsvgx', 05:40:35 INFO - 'tsvgr_opacity', 05:40:35 INFO - 'tart', 05:40:35 INFO - 'tscrollx', 05:40:35 INFO - 'cart']}, 05:40:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:40:35 INFO - 'tsvgr_opacity', 05:40:35 INFO - 'tart', 05:40:35 INFO - 'tscrollx', 05:40:35 INFO - 'cart']}, 05:40:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:35 INFO - 'talos_options': ['--disable-e10s'], 05:40:35 INFO - 'tests': ['tp5o']}, 05:40:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:35 INFO - 'tests': ['tp5o']}, 05:40:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:35 INFO - 'talos_options': ['--disable-e10s', 05:40:35 INFO - '--xperf_path', 05:40:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:35 INFO - 'tests': ['tp5n']}, 05:40:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:35 INFO - 'talos_options': ['--xperf_path', 05:40:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:35 INFO - 'tests': ['tp5n']}}, 05:40:35 INFO - 'talos.zip': {'path': '', 05:40:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:40:35 INFO - Running post-action listener: _resource_record_post_action 05:40:35 INFO - [mozharness: 2016-11-13 13:40:35.126200Z] Finished populate-webroot step (success) 05:40:35 INFO - [mozharness: 2016-11-13 13:40:35.126328Z] Running create-virtualenv step. 05:40:35 INFO - Running pre-action listener: _resource_record_pre_action 05:40:35 INFO - Running main action method: create_virtualenv 05:40:35 INFO - Creating virtualenv /builds/slave/test/build/venv 05:40:35 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 05:40:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:40:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:40:35 INFO - Using real prefix '/tools/python27' 05:40:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:40:35 INFO - Installing distribute.............................................................................................................................................................................................done. 05:40:38 INFO - Installing pip.................done. 05:40:38 INFO - Return code: 0 05:40:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:40:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:40:39 INFO - Reading from file tmpfile_stdout 05:40:39 INFO - Output received: 05:40:39 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) 05:40:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100bf4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3b04bfbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a08d30>, '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 0x10082edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a00b20>, '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.XLpMTWadOD/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.OGojxSPos2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 05:40:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:40:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLpMTWadOD/Render', 05:40:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:39 INFO - 'HOME': '/Users/cltbld', 05:40:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:39 INFO - 'LOGNAME': 'cltbld', 05:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:39 INFO - 'MOZ_NO_REMOTE': '1', 05:40:39 INFO - 'NO_EM_RESTART': '1', 05:40:39 INFO - 'PAGER': '/bin/cat', 05:40:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:39 INFO - 'PWD': '/builds/slave/test', 05:40:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:39 INFO - 'SHELL': '/bin/bash', 05:40:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OGojxSPos2/Listeners', 05:40:39 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 05:40:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:39 INFO - 'USER': 'cltbld', 05:40:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:39 INFO - 'XPC_FLAGS': '0x0', 05:40:39 INFO - 'XPC_SERVICE_NAME': '0', 05:40:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:39 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 05:40:39 INFO - Cleaning up... 05:40:39 INFO - Return code: 0 05:40:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100bf4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3b04bfbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a08d30>, '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 0x10082edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a00b20>, '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.XLpMTWadOD/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.OGojxSPos2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 05:40:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:40:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLpMTWadOD/Render', 05:40:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:39 INFO - 'HOME': '/Users/cltbld', 05:40:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:39 INFO - 'LOGNAME': 'cltbld', 05:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:39 INFO - 'MOZ_NO_REMOTE': '1', 05:40:39 INFO - 'NO_EM_RESTART': '1', 05:40:39 INFO - 'PAGER': '/bin/cat', 05:40:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:39 INFO - 'PWD': '/builds/slave/test', 05:40:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:39 INFO - 'SHELL': '/bin/bash', 05:40:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OGojxSPos2/Listeners', 05:40:39 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 05:40:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:39 INFO - 'USER': 'cltbld', 05:40:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:39 INFO - 'XPC_FLAGS': '0x0', 05:40:39 INFO - 'XPC_SERVICE_NAME': '0', 05:40:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:39 INFO - Downloading/unpacking psutil>=3.1.1 05:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:40:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:43 INFO - Installing collected packages: psutil 05:40:43 INFO - Running setup.py install for psutil 05:40:43 INFO - building 'psutil._psutil_osx' extension 05:40:43 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 05:40:43 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 05:40:43 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 05:40:44 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 05:40:44 INFO - building 'psutil._psutil_posix' extension 05:40:44 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 05:40:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:40:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:40:44 INFO - ^ 05:40:44 INFO - 1 warning generated. 05:40:44 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 05:40:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:44 INFO - Successfully installed psutil 05:40:44 INFO - Cleaning up... 05:40:44 INFO - Return code: 0 05:40:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:40:44 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')]} 05:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:44 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')]} 05:40:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:44 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 0x100bf4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3b04bfbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a08d30>, '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 0x10082edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a00b20>, '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.XLpMTWadOD/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.OGojxSPos2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 05:40:44 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 05:40:44 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 05:40:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLpMTWadOD/Render', 05:40:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:44 INFO - 'HOME': '/Users/cltbld', 05:40:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:44 INFO - 'LOGNAME': 'cltbld', 05:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:44 INFO - 'MOZ_NO_REMOTE': '1', 05:40:44 INFO - 'NO_EM_RESTART': '1', 05:40:44 INFO - 'PAGER': '/bin/cat', 05:40:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:44 INFO - 'PWD': '/builds/slave/test', 05:40:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:44 INFO - 'SHELL': '/bin/bash', 05:40:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OGojxSPos2/Listeners', 05:40:44 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 05:40:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:44 INFO - 'USER': 'cltbld', 05:40:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:44 INFO - 'XPC_FLAGS': '0x0', 05:40:44 INFO - 'XPC_SERVICE_NAME': '0', 05:40:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:40:44 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 05:40:44 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 05:40:44 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 05:40:44 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 05:40:44 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 05:40:44 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 05:40:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:40:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:40:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:40:47 INFO - Installing collected packages: mozsystemmonitor 05:40:47 INFO - Running setup.py install for mozsystemmonitor 05:40:47 INFO - Successfully installed mozsystemmonitor 05:40:47 INFO - Cleaning up... 05:40:47 INFO - Return code: 0 05:40:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:40:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:47 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 0x100bf4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3b04bfbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a08d30>, '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 0x10082edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a00b20>, '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.XLpMTWadOD/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.OGojxSPos2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 05:40:47 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 05:40:47 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 05:40:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLpMTWadOD/Render', 05:40:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:47 INFO - 'HOME': '/Users/cltbld', 05:40:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:47 INFO - 'LOGNAME': 'cltbld', 05:40:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:47 INFO - 'MOZ_NO_REMOTE': '1', 05:40:47 INFO - 'NO_EM_RESTART': '1', 05:40:47 INFO - 'PAGER': '/bin/cat', 05:40:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:47 INFO - 'PWD': '/builds/slave/test', 05:40:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:47 INFO - 'SHELL': '/bin/bash', 05:40:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OGojxSPos2/Listeners', 05:40:47 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 05:40:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:47 INFO - 'USER': 'cltbld', 05:40:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:47 INFO - 'XPC_FLAGS': '0x0', 05:40:47 INFO - 'XPC_SERVICE_NAME': '0', 05:40:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:47 INFO - Downloading/unpacking jsonschema==2.5.1 05:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:40:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:40:50 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 05:40:50 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 05:40:50 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 05:40:50 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 05:40:50 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 05:40:50 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 05:40:50 INFO - Downloading functools32-3.2.3-2.tar.gz 05:40:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:40:50 INFO - warning: no files found matching '*.txt' 05:40:50 INFO - no previously-included directories found matching 'build' 05:40:50 INFO - no previously-included directories found matching 'dist' 05:40:50 INFO - no previously-included directories found matching '.git*' 05:40:50 INFO - Installing collected packages: jsonschema, functools32 05:40:50 INFO - Running setup.py install for functools32 05:40:50 INFO - warning: no files found matching '*.txt' 05:40:50 INFO - no previously-included directories found matching 'build' 05:40:50 INFO - no previously-included directories found matching 'dist' 05:40:50 INFO - no previously-included directories found matching '.git*' 05:40:50 INFO - Successfully installed jsonschema functools32 05:40:50 INFO - Cleaning up... 05:40:51 INFO - Return code: 0 05:40:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:40:51 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')]} 05:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:51 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')]} 05:40:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:51 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 0x100bf4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3b04bfbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a08d30>, '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 0x10082edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a00b20>, '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.XLpMTWadOD/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.OGojxSPos2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 05:40:51 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 05:40:51 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 05:40:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLpMTWadOD/Render', 05:40:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:51 INFO - 'HOME': '/Users/cltbld', 05:40:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:51 INFO - 'LOGNAME': 'cltbld', 05:40:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:51 INFO - 'MOZ_NO_REMOTE': '1', 05:40:51 INFO - 'NO_EM_RESTART': '1', 05:40:51 INFO - 'PAGER': '/bin/cat', 05:40:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:51 INFO - 'PWD': '/builds/slave/test', 05:40:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:51 INFO - 'SHELL': '/bin/bash', 05:40:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OGojxSPos2/Listeners', 05:40:51 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 05:40:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:51 INFO - 'USER': 'cltbld', 05:40:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:51 INFO - 'XPC_FLAGS': '0x0', 05:40:51 INFO - 'XPC_SERVICE_NAME': '0', 05:40:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:40:51 INFO - Cleaning up... 05:40:51 INFO - Return code: 0 05:40:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:40:51 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')]} 05:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:51 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')]} 05:40:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:51 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 0x100bf4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3b04bfbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a08d30>, '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 0x10082edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a00b20>, '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.XLpMTWadOD/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.OGojxSPos2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 05:40:51 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 05:40:51 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 05:40:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLpMTWadOD/Render', 05:40:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:51 INFO - 'HOME': '/Users/cltbld', 05:40:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:51 INFO - 'LOGNAME': 'cltbld', 05:40:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:51 INFO - 'MOZ_NO_REMOTE': '1', 05:40:51 INFO - 'NO_EM_RESTART': '1', 05:40:51 INFO - 'PAGER': '/bin/cat', 05:40:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:51 INFO - 'PWD': '/builds/slave/test', 05:40:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:51 INFO - 'SHELL': '/bin/bash', 05:40:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OGojxSPos2/Listeners', 05:40:51 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 05:40:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:51 INFO - 'USER': 'cltbld', 05:40:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:51 INFO - 'XPC_FLAGS': '0x0', 05:40:51 INFO - 'XPC_SERVICE_NAME': '0', 05:40:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:51 INFO - Downloading/unpacking blobuploader==1.2.4 05:40:51 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 05:40:51 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 05:40:51 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 05:40:51 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 05:40:51 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 05:40:51 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 05:40:54 INFO - Downloading blobuploader-1.2.4.tar.gz 05:40:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:40:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:40:54 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 05:40:54 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 05:40:54 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 05:40:54 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 05:40:54 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 05:40:54 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 05:40:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:40:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:40:54 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 05:40:54 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 05:40:54 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 05:40:54 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 05:40:54 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 05:40:54 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 05:40:55 INFO - Downloading docopt-0.6.1.tar.gz 05:40:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:40:55 INFO - Installing collected packages: blobuploader, requests, docopt 05:40:55 INFO - Running setup.py install for blobuploader 05:40:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:40:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:40:55 INFO - Running setup.py install for requests 05:40:55 INFO - Running setup.py install for docopt 05:40:56 INFO - Successfully installed blobuploader requests docopt 05:40:56 INFO - Cleaning up... 05:40:56 INFO - Return code: 0 05:40:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:56 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 0x100bf4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3b04bfbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a08d30>, '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 0x10082edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a00b20>, '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.XLpMTWadOD/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.OGojxSPos2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 05:40:56 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 05:40:56 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 05:40:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLpMTWadOD/Render', 05:40:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:56 INFO - 'HOME': '/Users/cltbld', 05:40:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:56 INFO - 'LOGNAME': 'cltbld', 05:40:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:56 INFO - 'MOZ_NO_REMOTE': '1', 05:40:56 INFO - 'NO_EM_RESTART': '1', 05:40:56 INFO - 'PAGER': '/bin/cat', 05:40:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:56 INFO - 'PWD': '/builds/slave/test', 05:40:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:56 INFO - 'SHELL': '/bin/bash', 05:40:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OGojxSPos2/Listeners', 05:40:56 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 05:40:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:56 INFO - 'USER': 'cltbld', 05:40:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:56 INFO - 'XPC_FLAGS': '0x0', 05:40:56 INFO - 'XPC_SERVICE_NAME': '0', 05:40:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:40:56 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-pL5JmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:40:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:40:56 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-PmytIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:40:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:40:56 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-6bE57H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:40:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:40:56 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-pckMcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:40:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:40:56 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-7PRA4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:40:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:40:56 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-txMpnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:40:57 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-TRF4W2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:40:57 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-4xiZWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:40:57 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-DMe4Hy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:40:57 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-TyUUuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:40:57 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-8ACQgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:57 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-2S5seK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:57 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-yAGsUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:57 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-B7d5GK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:57 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-K3F0V0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:58 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-4YuL5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:58 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-TCwEsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:40:58 INFO - Running setup.py install for manifestparser 05:40:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:40:58 INFO - Running setup.py install for mozcrash 05:40:58 INFO - Running setup.py install for mozdebug 05:40:58 INFO - Running setup.py install for mozdevice 05:40:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:40:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:40:58 INFO - Running setup.py install for mozfile 05:40:58 INFO - Running setup.py install for mozhttpd 05:40:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:40:59 INFO - Running setup.py install for mozinfo 05:40:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:40:59 INFO - Running setup.py install for mozInstall 05:40:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:40:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:40:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:40:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:40:59 INFO - Running setup.py install for mozleak 05:40:59 INFO - Running setup.py install for mozlog 05:40:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:40:59 INFO - Running setup.py install for moznetwork 05:40:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:40:59 INFO - Running setup.py install for mozprocess 05:41:00 INFO - Running setup.py install for mozprofile 05:41:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:41:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:41:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:41:00 INFO - Running setup.py install for mozrunner 05:41:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:41:00 INFO - Running setup.py install for mozscreenshot 05:41:00 INFO - Running setup.py install for moztest 05:41:00 INFO - Running setup.py install for mozversion 05:41:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:41:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:41:00 INFO - Cleaning up... 05:41:00 INFO - Return code: 0 05:41:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:41:00 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')]} 05:41:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:00 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')]} 05:41:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:00 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 0x100bf4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3b04bfbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a08d30>, '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 0x10082edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a00b20>, '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.XLpMTWadOD/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.OGojxSPos2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 05:41:00 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 05:41:00 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 05:41:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLpMTWadOD/Render', 05:41:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:00 INFO - 'HOME': '/Users/cltbld', 05:41:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:00 INFO - 'LOGNAME': 'cltbld', 05:41:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:00 INFO - 'MOZ_NO_REMOTE': '1', 05:41:00 INFO - 'NO_EM_RESTART': '1', 05:41:00 INFO - 'PAGER': '/bin/cat', 05:41:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:00 INFO - 'PWD': '/builds/slave/test', 05:41:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:00 INFO - 'SHELL': '/bin/bash', 05:41:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OGojxSPos2/Listeners', 05:41:00 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 05:41:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:00 INFO - 'USER': 'cltbld', 05:41:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:00 INFO - 'XPC_FLAGS': '0x0', 05:41:00 INFO - 'XPC_SERVICE_NAME': '0', 05:41:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:41:01 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-w1YJxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:41:01 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)) 05:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:41:01 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-MHUqHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:41:01 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)) 05:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:41:01 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-5BlymC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:41:01 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)) 05:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:41:01 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-XYWSp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:41:01 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)) 05:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:41:01 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-I246FV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:41:01 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)) 05:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:41:01 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-4RYp41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:41:01 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)) 05:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:41:01 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-VX3W79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:41:01 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)) 05:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:41:01 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-4nH4qR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:41:01 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)) 05:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:41:01 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-pY2JgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:41:02 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)) 05:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:41:02 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-bhOM5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:41:02 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)) 05:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:41:02 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-cdz8eH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:41:02 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)) 05:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:41:02 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-0hO3Pw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:41:02 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)) 05:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:41:02 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-Lr9h4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:41:02 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)) 05:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:41:02 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-htWWlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:41:02 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)) 05:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:02 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-XL5RXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:02 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)) 05:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:41:02 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-vNreLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:41:02 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)) 05:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:41:02 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-txiqKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:41:02 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)) 05:41:02 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)) 05:41:02 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)) 05:41:02 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)) 05:41:02 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)) 05:41:02 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)) 05:41:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:41:02 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 05:41:02 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 05:41:02 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 05:41:02 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 05:41:02 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 05:41:02 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 05:41:05 INFO - Downloading blessings-1.6.tar.gz 05:41:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:41:05 INFO - Installing collected packages: blessings 05:41:05 INFO - Running setup.py install for blessings 05:41:05 INFO - Successfully installed blessings 05:41:05 INFO - Cleaning up... 05:41:05 INFO - Return code: 0 05:41:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:41:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:41:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:41:06 INFO - Reading from file tmpfile_stdout 05:41:06 INFO - Current package versions: 05:41:06 INFO - blessings == 1.6 05:41:06 INFO - blobuploader == 1.2.4 05:41:06 INFO - docopt == 0.6.1 05:41:06 INFO - functools32 == 3.2.3-2 05:41:06 INFO - jsonschema == 2.5.1 05:41:06 INFO - manifestparser == 1.1 05:41:06 INFO - mozInstall == 1.12 05:41:06 INFO - mozcrash == 1.0 05:41:06 INFO - mozdebug == 0.1 05:41:06 INFO - mozdevice == 0.48 05:41:06 INFO - mozfile == 1.2 05:41:06 INFO - mozhttpd == 0.7 05:41:06 INFO - mozinfo == 0.9 05:41:06 INFO - mozleak == 0.1 05:41:06 INFO - mozlog == 3.4 05:41:06 INFO - moznetwork == 0.27 05:41:06 INFO - mozprocess == 0.23 05:41:06 INFO - mozprofile == 0.28 05:41:06 INFO - mozrunner == 6.13 05:41:06 INFO - mozscreenshot == 0.1 05:41:06 INFO - mozsystemmonitor == 0.3 05:41:06 INFO - moztest == 0.7 05:41:06 INFO - mozversion == 1.4 05:41:06 INFO - psutil == 3.1.1 05:41:06 INFO - requests == 1.2.3 05:41:06 INFO - wsgiref == 0.1.2 05:41:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:41:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:06 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 0x100bf4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3b04bfbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a08d30>, '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 0x10082edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a00b20>, '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.XLpMTWadOD/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.OGojxSPos2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 05:41:06 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 05:41:06 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 05:41:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLpMTWadOD/Render', 05:41:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:06 INFO - 'HOME': '/Users/cltbld', 05:41:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:06 INFO - 'LOGNAME': 'cltbld', 05:41:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:06 INFO - 'MOZ_NO_REMOTE': '1', 05:41:06 INFO - 'NO_EM_RESTART': '1', 05:41:06 INFO - 'PAGER': '/bin/cat', 05:41:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:06 INFO - 'PWD': '/builds/slave/test', 05:41:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:06 INFO - 'SHELL': '/bin/bash', 05:41:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OGojxSPos2/Listeners', 05:41:06 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 05:41:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:06 INFO - 'USER': 'cltbld', 05:41:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:06 INFO - 'XPC_FLAGS': '0x0', 05:41:06 INFO - 'XPC_SERVICE_NAME': '0', 05:41:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:06 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)) 05:41:06 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)) 05:41:06 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)) 05:41:06 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)) 05:41:06 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)) 05:41:06 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)) 05:41:06 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)) 05:41:06 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)) 05:41:06 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)) 05:41:06 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)) 05:41:06 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)) 05:41:06 INFO - Cleaning up... 05:41:06 INFO - Return code: 0 05:41:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:41:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:06 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 0x100bf4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3b04bfbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a08d30>, '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 0x10082edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a00b20>, '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.XLpMTWadOD/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.OGojxSPos2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 05:41:06 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 05:41:06 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 05:41:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLpMTWadOD/Render', 05:41:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:06 INFO - 'HOME': '/Users/cltbld', 05:41:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:06 INFO - 'LOGNAME': 'cltbld', 05:41:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:06 INFO - 'MOZ_NO_REMOTE': '1', 05:41:06 INFO - 'NO_EM_RESTART': '1', 05:41:06 INFO - 'PAGER': '/bin/cat', 05:41:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:06 INFO - 'PWD': '/builds/slave/test', 05:41:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:06 INFO - 'SHELL': '/bin/bash', 05:41:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OGojxSPos2/Listeners', 05:41:06 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 05:41:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:06 INFO - 'USER': 'cltbld', 05:41:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:06 INFO - 'XPC_FLAGS': '0x0', 05:41:06 INFO - 'XPC_SERVICE_NAME': '0', 05:41:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:41:06 INFO - Cleaning up... 05:41:06 INFO - Return code: 0 05:41:06 INFO - Running post-action listener: _resource_record_post_action 05:41:06 INFO - Running post-action listener: _start_resource_monitoring 05:41:06 INFO - Starting resource monitoring. 05:41:06 INFO - [mozharness: 2016-11-13 13:41:06.593924Z] Finished create-virtualenv step (success) 05:41:06 INFO - [mozharness: 2016-11-13 13:41:06.594796Z] Running install step. 05:41:06 INFO - Running pre-action listener: _resource_record_pre_action 05:41:06 INFO - Running main action method: install 05:41:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:41:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:41:06 INFO - Reading from file tmpfile_stdout 05:41:06 INFO - Detecting whether we're running mozinstall >=1.0... 05:41:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:41:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:41:06 INFO - Reading from file tmpfile_stdout 05:41:06 INFO - Output received: 05:41:06 INFO - Usage: mozinstall [options] installer 05:41:06 INFO - Options: 05:41:06 INFO - -h, --help show this help message and exit 05:41:06 INFO - -d DEST, --destination=DEST 05:41:06 INFO - Directory to install application into. [default: 05:41:06 INFO - "/builds/slave/test"] 05:41:06 INFO - --app=APP Application being installed. [default: firefox] 05:41:06 INFO - mkdir: /builds/slave/test/build/application 05:41:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:41:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:41:41 INFO - Reading from file tmpfile_stdout 05:41:41 INFO - Output received: 05:41:41 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:41:41 INFO - Running post-action listener: _resource_record_post_action 05:41:41 INFO - [mozharness: 2016-11-13 13:41:41.178751Z] Finished install step (success) 05:41:41 INFO - [mozharness: 2016-11-13 13:41:41.178904Z] Running run-tests step. 05:41:41 INFO - Running pre-action listener: _resource_record_pre_action 05:41:41 INFO - Running main action method: run_tests 05:41:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:41:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:41:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:41:41 INFO - 2016-11-13 05:41:41.194 screenresolution[1939:64926] starting screenresolution argv=screenresolution get 05:41:41 INFO - 2016-11-13 05:41:41.196 screenresolution[1939:64926] Display 0: 1600x1200x32@60 05:41:41 INFO - 2016-11-13 05:41:41.202 screenresolution[1940:64928] starting screenresolution argv=screenresolution list 05:41:41 INFO - Available Modes on Display 0 05:41:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:41:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:41:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:41:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:41:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:41:41 INFO - Intel Iris: 05:41:41 INFO - Chipset Model: Intel Iris 05:41:41 INFO - Type: GPU 05:41:41 INFO - Bus: Built-In 05:41:41 INFO - VRAM (Dynamic, Max): 1536 MB 05:41:41 INFO - Vendor: Intel (0x8086) 05:41:41 INFO - Device ID: 0x0a2e 05:41:41 INFO - Revision ID: 0x0009 05:41:41 INFO - Displays: 05:41:41 INFO - Display: 05:41:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:41:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:41:41 INFO - Display Serial Number: 1600x1200 60 05:41:41 INFO - Main Display: Yes 05:41:41 INFO - Mirror: Off 05:41:41 INFO - Online: Yes 05:41:41 INFO - Rotation: Supported 05:41:41 INFO - Return code: 0 05:41:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:41:41 INFO - Python 2.7.3 05:41:41 INFO - Return code: 0 05:41:41 INFO - grabbing minidump binary from tooltool 05:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:41 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 0x100a08d30>, '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 0x10082edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a00b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:41:41 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 05:41:41 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 05:41:41 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 05:41:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:41:41 INFO - Return code: 0 05:41:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:41:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:41:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:41:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:41:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:41:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--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 05:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0233 --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 05:41:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLpMTWadOD/Render', 05:41:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:41 INFO - 'HOME': '/Users/cltbld', 05:41:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:41 INFO - 'LOGNAME': 'cltbld', 05:41:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:41:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:41:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:41 INFO - 'MOZ_NO_REMOTE': '1', 05:41:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:41:41 INFO - 'NO_EM_RESTART': '1', 05:41:41 INFO - 'PAGER': '/bin/cat', 05:41:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:41 INFO - 'PWD': '/builds/slave/test', 05:41:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:41:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:41 INFO - 'SHELL': '/bin/bash', 05:41:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OGojxSPos2/Listeners', 05:41:41 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 05:41:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:41 INFO - 'USER': 'cltbld', 05:41:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:41 INFO - 'XPC_FLAGS': '0x0', 05:41:41 INFO - 'XPC_SERVICE_NAME': '0', 05:41:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--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 05:41:41 INFO - mozversion application_buildid: 20161113030203 05:41:41 INFO - mozversion application_changeset: b37be3d705d929ee52280051d58cedc70a47626f 05:41:41 INFO - mozversion application_display_name: Nightly 05:41:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:41:41 INFO - mozversion application_name: Firefox 05:41:41 INFO - mozversion application_remotingname: firefox 05:41:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:41:41 INFO - mozversion application_vendor: Mozilla 05:41:41 INFO - mozversion application_version: 52.0a1 05:41:41 INFO - mozversion platform_buildid: 20161113030203 05:41:41 INFO - mozversion platform_changeset: b37be3d705d929ee52280051d58cedc70a47626f 05:41:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:41:41 INFO - mozversion platform_version: 52.0a1 05:41:41 INFO - using testdate: 1479044501 05:41:41 INFO - actual date: 1479044501 05:41:41 INFO - starting webserver on 'localhost:52493' 05:41:41 INFO - SUITE-START | Running 2 tests 05:41:41 INFO - TEST-START | dromaeo_css 05:41:41 INFO - operating with platform_type : mac_ 05:41:41 INFO - Initialising browser for dromaeo_css test... 05:41:41 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpuwVASP/profile http://localhost:52493/getInfo.html) 05:41:44 INFO - PROCESS | 1950 | __metrics Screen width/height:1600/1200 05:41:44 INFO - PROCESS | 1950 | colorDepth:24 05:41:44 INFO - PROCESS | 1950 | Browser inner width/height: 1024/689 05:41:44 INFO - PROCESS | 1950 | __metrics 05:41:44 INFO - PROCESS | 1950 | 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" 05:41:44 INFO - PROCESS | 1950 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:41:45 INFO - TEST-INFO | 1950: exit 0 05:41:45 INFO - Browser initialized. 05:41:45 INFO - Running cycle 1/1 for dromaeo_css test... 05:41:45 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpuwVASP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:41:57 INFO - PROCESS | 1953 | RSS: Main: 229998592 05:41:57 INFO - PROCESS | 1953 | 05:42:48 INFO - PROCESS | 1953 | 1479044568650 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] 05:45:03 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52493/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52493/tests/dromaeo/cssquery-ext.html) 05:45:04 INFO - PROCESS | 1953 | RSS: Main: 363667456 05:45:04 INFO - PROCESS | 1953 | 05:48:07 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52493/tests/dromaeo/cssquery-ext.html (next: http://localhost:52493/tests/dromaeo/cssquery-jquery.html) 05:48:07 INFO - PROCESS | 1953 | RSS: Main: 371556352 05:48:07 INFO - PROCESS | 1953 | 05:51:10 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52493/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52493/tests/dromaeo/cssquery-mootools.html) 05:51:10 INFO - PROCESS | 1953 | RSS: Main: 355647488 05:51:10 INFO - PROCESS | 1953 | 05:54:13 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52493/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52493/tests/dromaeo/cssquery-prototype.html) 05:54:13 INFO - PROCESS | 1953 | RSS: Main: 343371776 05:54:13 INFO - PROCESS | 1953 | 05:57:17 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52493/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52493/tests/dromaeo/cssquery-yui.html) 05:57:17 INFO - PROCESS | 1953 | RSS: Main: 345862144 05:57:17 INFO - PROCESS | 1953 | 06:00:21 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52493/tests/dromaeo/cssquery-yui.html (next: http://localhost:52493/tests/dromaeo/cssquery-dojo.html) 06:00:21 INFO - PROCESS | 1953 | RSS: Main: 460812288 06:00:21 INFO - PROCESS | 1953 | 06:00:21 INFO - PROCESS | 1953 | __start_tp_report 06:00:21 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 06:00:21 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 06:00:21 INFO - PROCESS | 1953 | |i|pagename|runs| 06:00:21 INFO - PROCESS | 1953 | |0;dojo.html;2865.853658536585;3199.456092464281;3203.407369636617;3226.7257283130934;3234.1267857678426;5525.198846167304;5535.14205298179;5577.721113944302;5621.887562248752;5624.15637654352;4774.7851346689395;5601.131824567188;5648.435156484349;5723.713814309278;5780.682062486561;5048.242763585455;5131.281620573122;5153.381594208681;5186.247994040862;5190.299309593194;5197.636165468419;5230.34620672416;5254.106801843695;5265.605079619025;5266.81566145185;5079.339685840854;5224.895502089937;5225.973870130625;5267.209918512215;5316.282301889234;5148.253503242009;5151.304573882516;5168.3281173447585;5168.689878607295;5185.274061631375;5080.618953578496;5139.58883289336;5145.665531740462;5147.433782283946;5181.1969144782615;5600.691961942091;5622;5623.915641265385;5624.690642014688;5633.464820842013;6095.420935011167;6096;6101.786437474667;6104.877902441926;6112.083187521863;6103.435603539908;6104.307579233822;6169.784057557964;6170.167027451282;6185.474234690012;5667.603267771257;5775.220345253421;5795.043817770021;5822.912656310116;5832.4459176378605;6196.1635179250225;6199.535034872402;6199.752009919643;6202.503799696014;6209.720562574695;5901.29184497863;6120.357362476965;6122;6125.663088530174;6138.969305153446;6111.236095488064;6138.7237574309265;6144.279601711528;6148.569600128038;6157.353477884847;5167.457416971239;6134.447899689049;6183.536234782409;6186;6186.443220110212;6167.198264225622;6192.504599632019;6211.3167551569295;6216.564840461215;6219.284782250009;4802.423709154924;4854.611631069506;4891;4904.141775189328;4931.112399768076;4302.806373713191;4351.282038463682;4360.978195109004;4401.559844015637;4424.13729322779;5739.081746920473;5741.655500669973;5748.798792042342;5751.22358481608;5762.94237057624;6106.39999602577;6149.66176860268;6155.322914479404;6165.229346331708;6169.691515424211;5726.541876649858;5727.3699893011735;5738.885222295531;5748.942510574924;5752.539796816244;4620.306953956933;5196.32447781786;5291.1798671206025;5304.814331498379;5311.442298558596;6186.752529898754;6248.312685604489;6251.718672659832;6254.906176407358;6259.1550140730415;6232.345603711727;6250.093736408105;6258.937410625836;6260.561760676709;6273.1844860167885;6095.05526643371;6179.721912513858;6234.096056071753;6250.218722659668;6444.387783160592;4740.905181896412;6043.8186854394435;6084.756609735561;6104.481119104926;6116.35778243278;4966.528179822911;6205.317415084247;6231.719572619152;6241.906371404523;6244.188255526752;5999.730012149376;6018.608790428608;6033.487153591994;6041.516678665608;6043.274807023009;5872.530197584098;5925.555583331181;5931.436513531207;6047.0627052806885;6050.878982420239;5759.539236860957;5817.534597232296;5824.388439213991;5875.41245875409;5902.557308201816;5983.4914032306;5993.190919226023;6003.129546215861;6066.636001839904;6096.481799046954;5860.853478663067;5899.439553242435;5918.644881307136;5934.495567876606;5944.197533332945 06:00:21 INFO - PROCESS | 1953 | |1;ext.html;447.3178402935523;450.45494951109157;452.2312069481878;494.0884068892894;503.8891443882346;29931.700682993163;31857.884974025914;32054.67945320546;40048.99877503064;40201.99495012626;41376.58623413765;41785.74642760715;41814.37278440818;41851.162976740445;41887.16225675492;9232.769180770467;9417.529123543829;9426.858597121049;9433.858492122623;9437.716868493957;9331.626734930594;9414.905850941506;9451.432914025147;9457.858132128023;9460.24318054554;9437.480938548377;9477.336586438953;9484.19384352331;9484.241260699162;9495.430274183536;9386.906130938705;9429.952850235773;9435.622575096988;9443.338966272364;9475.810483790354;9346.0653934606;9387.57755900986;9394.181745476399;9394.295427842939;9404.200642945358;39402.60597394018;39691.20617587632;39816.800915995525;39853.80073099645;39892.80053599743;3238.3685181389637;3259.5762550868235;3374.1395944034116;3422.3155368926314;3624.7100231981376;25353.87323063391;29292.70707292921;30198.69801301981;30347;30350.392992140256;2854.272160599034;3105.394448082625;3247.5453436518906;3276.3611095836322;3309.7683162178655;494855;495090;500879;501282.9871701273;502463.48768255924;59904.101438477584;60356.69821650864;60402;60425.39574604286;60460.09309860268;59146.70426647925;59158.11262831061;59201.40798592045;59223.111653325235;59243.70378148081;15523.767143492856;15777;15792;15803;15831.762523562156;12070.879291207151;12100.757984840255;12134.211276267191;12152.69618259533;12152.817707734392;16718.58203544921;17889.10554472271;18214.63570728578;18268.360607378945;18312.161614949706;6038.396160384014;7351.705931762776;7483.214262502468;7541.058986995076;7582.924170758332;881.7266647339345;948.3598570964709;987.8814542254976;1024.08856118055;1038.6883934819523;29710.69670682974;29731.702682972893;29785.40429191404;29833.64525881548;29937.85031074874;1085.2891356161726;1112.193659596802;1119.7200699825044;1133.5692436873935;1149.8275258711094;15698.607534811721;15991.440299589458;16428.38575513385;16601.335946562245;16676.249568769435;638861;643284;645341;646154;646813;56432.71783641056;56908;56971;57005.71497142571;57068;1327.7410904873457;1344.4286178374152;1364.5087768403366;1368.8631136886231;1371.5062577472102;28935.131946041653;29538.556921645762;29575.556366654087;30551.694483054885;30577.08268751941;48773.512264878314;49384;49389.25916111333;49460;49463.752681236365;574383;582033.0898345481;582267;584261;584775;24641.87679061665;24710.50578988374;24794.008239670213;24995.87502062478;25032.749672503043;43481;43545.346819797094;43962.78018609886;44210.336844948;44282.77858610686;36251.27497449984;36260.274794503435;36987.36213007148;37267.62732372642;37685.81157094197;35515.82242088876;36003;36774.81612592027;37096.6290337104;37254.8137259312;34969;35297.29405411929;35629.93110206698;35803.10492237715;36205;30782.384352313275;32161.517577235893;32241.03276901697;32246.516302255015;32251;23665.881670591538;23723.71325086312;24058.51882962366;24404.145854895705;24414.633780493663 06:00:21 INFO - PROCESS | 1953 | |2;jquery.html;347.3816606440537;366.15966357210203;369.5565321614062;376.88881779874936;401.3357892687601;14164.362603682834;14234.572962811118;15404.306806193707;19530.316438924605;20168.495787605316;15467;18768.061596920168;19422.70865937012;19938.501537461572;19990.60018799623;5389.73051347432;5722.399148089453;5750.654960702351;5753.884922301551;5760.855978600526;5328.520433161015;5529.474699903523;5567.9977604031255;5586.972065139668;5625.80309689161;4966.850994470154;5368.785248590052;5537.280153580029;5537.916931246034;5541.141123125902;5284.4187139414635;5496.862578435571;5532.3637781655025;5560.388357280696;5565.777368905239;4883.951160488421;4888.755562221911;4889.559939605417;4891.657583969123;4894.486078961693;24091;24428.511429771304;24441.755582444304;24476.388090297885;24732.258032259062;2192.2765487389124;2220.4781876259067;2295.5064661097945;2300.8734519601417;2323.1752727781563;14283.64290892736;14434.711305773932;14551.78172327416;14709.411623535047;15491.302891369915;1744.302279088375;1785.4554360919915;1829.4603092087866;1837.2191818477095;1867.0384751852807;109445;115019.99999999916;115295;115562;115584;7756.883646745303;9891.208703303719;10198.082172604501;10718.41048742311;10971;12975.404919016235;13005.804912926315;13007.739845203232;13010.479580816662;13015.869841301655;7933.9603301983125;8157.347412207009;8225.642768943177;8299.377546683902;8312.8753068704;7483.214262502468;8183.877241841377;8192.75421737349;8337.749867503984;8383.580820958927;5422.43064478232;6184.3920580648455;6325.272593651697;6329.620222786648;6336.303006669263;1793.641271745656;1807.8101799310882;1812.6555954368628;1820.2445984916112;1834.798242109474;949.7008442340641;950.4914870544226;967.3808762392075;977.2621670638622;994.1897353656747;18061.548461288574;21437.785622143892;21452.78547214539;21488.57022859534;21506.892465537887;893.3155866620763;921.4102974096584;974.936629119105;981.16600889245;993.8807343118872;9640.807183856283;10762.56949722018;11257.662270131908;11468.885311146782;11492.770144597062;523885;568026;571193;592552;593127;112815.4359228215;114452.42773786078;115616;116229.41885290689;117087;967.458223394887;998.4608311511847;999.6001599360169;1000.4047591682937;1021.3208216536069;17693;18672.439826805217;18726.250949962396;18851.811481885554;18974.620507589494;43238;48706.05175792929;50247.49752502428;50333.24500132428;51772.74113629408;66862.66568667126;70944;72317;74333.88499172626;75205.11987200363;7489.438292128221;7523.8495230093995;7742.303192712683;7756.767296981099;7938.5236885787035;10019.348742331726;10677.878822723502;12384.56654017105;13038.47846086146;13095.541656041993;11010.064144547483;11072.20875602228;11249.887501124884;11287.20989530725;11321.7735645285;9115.407498512575;9603.03969603034;11351.318920864775;11424.771504570028;11545.307281563133;11189.496472658588;11326.037286830382;11415.771684566429;11590.130740194687;11682.357470338873;6595.769148079794;7655.846883062419;7778.922210778046;7813.843723125619;7907.446478746432;10860.619878304538;11150.219484635998;11209;11257.662270131908;11270.887291127312 06:00:21 INFO - PROCESS | 1953 | |3;mootools.html;1317.901157413193;1447.7683570628699;1461.7588097963833;1632.3389027443889;1817.4365946556568;7275.454340924425;7662.118856331524;7989.201079892001;8260.876086858701;8270.131636178205;7249.3113154250395;7998.920145780333;8085.91914080859;8169.183081691843;8172.427930044884;2207.315732123043;2239.2722365231366;2246.6742322363248;2248.022110381981;2248.9662655060188;2245.0346351069034;2289.954200915981;2297.27635794725;2298.080767692928;2310.06442390832;1854.6198029404006;1913.913873875672;2228.0530774420886;2271.250487339182;2282.9657555136605;2156.773538778429;2191.233068426054;2220.9888950555146;2223.488597622556;2228.0419419649543;2236.4632488202715;2251.5609456156058;2253.2113760183806;2259.4690247791755;2259.920902768395;7985.720499782537;8164.591770411455;8169.5098294102545;8173.877391839127;8181.71364002263;4854.660173787836;5056.140456122468;5066.164082926312;5089.4147173074825;5090.796368145269;3188.3942051010235;3391.3217356528794;3396.320735852815;3448.327576122657;3451.033710561022;1000.1248907206194;1008.5915204342253;1049.9160067194607;1053.615430367918;1068.358984609236;10380.740481487948;10425.895741042568;10522.947705229491;10534.367937923673;10535.315204511682;3981.5421226559315;4082.0611259410502;4144.668426525931;4173.08192197716;4285.164392943346;7286.7085316586745;7700.383969282444;8092.312153467021;8447.239748422671;8504.957475212585;4412.338149277634;4832.888435659818;4959.950400496021;4968.826091086795;4973.229149481836;3857.1707082977296;4199.118185181085;4213.410122582841;4329.848455304049;4566.977165114153;3510.578730552299;3517.296540691872;3520.9471857922154;3561.821908904544;3563.6436356364675;1053.2890299048113;1054.920880933933;1110.4225802582612;1123.5842738186923;1132.671525257666;809.2595275323014;810.3112354498629;860.9009963854112;903.9322050846213;941.5433514745343;4907.533784290486;4920;5466.6993315367245;5518.586106042063;5526.92225016118;845.691322667222;882.4440602420434;895.9776005599782;900.9684661036832;910.5219759626117;3147.874085036573;3758.379867321861;3865.0530619997717;3867.941980870289;3894.727369084137;8866.778330541789;9702.51487425626;9855.852162217572;9858.359206651545;9884.209263258923;8367.414281000272;9491.193248573949;9847.212223022141;9856;9865.210783137332;7333.449991250677;7603.7338693146585;7608.80977975544;7609.733659322008;7618.923810761821;4841.467438581823;5308.124159513637;5368.087425137658;5443.265159203615;5622.100463925753;9025.909740902769;9079.45523268606;9096.681616143404;9117.589708463267;9209.079092090737;7392.58779801299;7714.884276735741;9116.544172791466;9244.676436324697;9353.20497757671;6364.363563643598;6630.336966303524;6636.800895973129;6637.369449902251;6638.468922486093;7703.2296770324765;7719.807004824925;7722.420818438527;7796.298333150044;7823.100343460347;6524.347565243438;6920.238773734985;6925.411340036153;6952.270011648704;6972.965135174291;6760.18877734675;6813.080234168522;6834.45324374039;6871.622060786504;6894.276101009322;6463.903041454476;6574.80275591733;6580.80257592273;6583.539152259296;6584.275729669828;4627.9768601156775;4725.9054818904115;4798.1123492154065;4847.733374664427;4883.706977581356;3921.5686274510354;4684.8360307389075;4855.95144048555;4922.114019476386;4987.60099192071 06:00:21 INFO - PROCESS | 1953 | |4;prototype.html;296.8931184773482;302.40275455974455;306.2481607653211;313.6424476097248;315.5708236797956;5158.174692049273;5196.6622169559;5224.764885580148;5240.397354304256;5250.684958902469;5132.769025393857;5169.148984006911;5169.405518365389;5184.662996905198;5197.454267301936;3762.758289764371;3916.454304701938;3927;3945.60543945606;3956.6834653227675;3258.657840926704;3511.6839484446396;3915.138669492707;3931.272714547818;3974.4237085622713;3311.172206948263;3938.212357528506;3947.9210415791667;3950.091478959841;3951.8857411284966;3624.420092785168;3968;3970.801459927021;3982.761034337949;3994.4407782910416;3259.8044117353033;3321.501774733785;3403.910748560462;3448.2758620689683;3469.5142680024815;5000.399952005746;5021.246812978046;5025.195968644999;5048.14181589127;5062.594992400636;3604.6395360464007;3731.589525152231;4457.264551349023;4469.977650111761;4517.119161763458;4722.6221902247735;5113.162194981784;5394.244805727201;5428.344354971732;5440.415802493784;3778.6572325158304;3792.2036372361836;3797.39241721326;3807.1053302473897;3815.04623844039;5467.480589344006;5574.637648552791;5589.189567512688;5748.240844394672;5824.563157763185;3148.307372378073;3439.6044454887506;3842.538895332522;3859.1702783901596;3887.3974533947285;3622.130688634709;3910.237503686754;3911.3155197840265;3952.881413557598;3958.8614398495915;3725.5529336479794;4518.864434066983;4551.795169217393;4558.817647294138;4572.565606267466;5209.583233341324;5480.6437581557075;5491.36849262332;5526.4197259288;5532.75102620383;1076.8007918535095;1094.819354806464;1257.9433925473193;1260.6470188347278;1266.1326991011188;830.2320353672949;842.839860426517;880.190224993008;890.7505898348472;891.6522556203086;4818.229083346648;4838.879028024257;4840.806367745251;4869.561739443467;4939.456659767423;4842.789302674331;4894.828680996148;4988.902441462872;5229.424763276036;5256.26412302278;4024.63778259958;4396.142752163298;4409.112031619295;4564.452265728134;4856.708597484162;2370.63255195445;2504.8622325771894;2596.4417650205296;2625.146827281103;2818.8167769094944;4892.339534162914;5456.263404440429;5491.560675145979;5516.3380394352935;5549.366279156869;5060.595152387874;5269.578433725293;5329.760160792696;5399.83800485986;5422.783088676409;4880.267959806058;4908.803647854117;4912.70523768575;5470.452954704578;5483.341998960071;4626.653001024869;4763.7856296467435;4837.234409440599;5107.616928730435;5358.97320513395;4825.469198388104;4970.378702662168;5223.373195216598;5226.843194704165;5276.148411659433;4589;4768.737719425465;4778.450478195052;5035.446100928822;5479.479449452296;3038.468268053126;3287.868485260563;3724.42271447926;3848.2688289226076;3857.4213867920034;4421.889452763707;5206.765695543634;5348.919766203432;5682.687452190004;5691.26013618227;5177.456367081402;5233.816816411408;5386.811461598825;5454.536364409069;5491.835244942658;5375.919361209663;5411.83764487066;5424;5444.891102178013;5445.618806683494;4738.929345600175;4981.501849814989;5311.3891902431715;5332.333833083459;5371.248025276543;4624.514425985223;4742.762861856962;5065.985738485595;5433.505785908907;5450.509454149145;4472.664550158686;4531.773411329486;4883.389576302962;4900.289458028495;5139.820106296262 06:00:21 INFO - PROCESS | 1953 | |5;yui.html;684.900689400037;726.2846096594857;751.2862780358661;782.6065448069452;794.7059587952458;14492.492762753305;15012.099274043565;15057.322420491077;15116.848831511696;15118.697626047486;13353.465861365557;13496.865031349684;13500.122492038035;13515.729685406286;13528.391222394992;645.6029541831763;673.787756856591;674.1404708996045;795.8209402884333;820.5774026376421;835.1356346181703;839.9244068033876;841.6338892581716;844.2992316377423;845.9788505287386;899.6896070855545;900.4957223954574;901.342020325163;901.9323550733689;904.0055938467699;898.5237823953282;899.1817446124029;900.0504467786483;900.0909081827336;901.4140808474478;513.453172371427;527.9181726832347;640.4044238857861;651.6611362091686;667.502710480695;13955.372008259652;13978;13996.370163342572;14003.859961400358;14012.299385030708;12813.487460501568;12984.28586427746;13201.801972970317;13214.471421143142;13414.932925335408;10080.193584513221;11721.88278117225;11951.521939122425;12004.759904801942;12464.875351246463;4353.25994580922;4381.561843815625;4449.399331090293;4544.500104988449;4551.65862560306;86127.13872861254;86964.26071478598;87200;88896.65746090903;91095.54452227763;9402.294827887936;10331.173506119492;10399.064084232456;10420.947895260475;10739.731506712395;19719.211231550576;19948.90025549892;19956.90021549912;19964.30124945649;20166.798332016493;6744.831379215559;7012.263712310134;7147.517144180437;7168.784936451915;7358.816029599303;4145.440365550612;4166.375043743463;4221.071364299849;4231.090315582103;4336.436263285753;3035.1501579557757;3129.186411532972;3129.6244450666068;3132.7493800496363;3139.089663997461;583.3699604427209;585.9765609375662;614.1494030767419;634.5970308853819;634.6541135081392;524.4126578232405;528.2419727690776;582.6911736779514;594.4560726934806;615.7075389190098;8121.309688676529;8290.37822163328;8379.162083791573;8474.364422668325;8748.781280467912;477.28169105496005;553.9972300138473;567.140781715701;574.2678085441013;610.7343305661963;4047;4581.381513495702;4635;4660.930086048712;4777;114768;115197;115791;116100;116180;60239.397606023376;60778;61802.69098654478;62125;81337.18662813415;69108;89533.55233223923;90619;91037;92997.99999999865;9198.49408282551;9244.722658320261;9265.675701350421;9298.860517092384;9326;55371.5082821266;55604.443955559924;55769.721151393984;56360.71819640876;56718.716406417705;61757;68299;78383;81868.18131818844;83008;11708.722431949069;12259.448324825582;12296.385180740748;12333.032738287975;12553.874461255271;13988.860111399163;14350.354234059283;14360.353784079532;14517.78223326672;14672.3397447113;10632.787344252916;13049.869501305246;13198;13259.734805304033;13595.252261125734;11593.24643898144;12617.747645047231;13040.217586944813;13053.078598593289;13950;10949.233553651167;11653.30080195191;12525.93737031309;12626.747465050465;13356.532521361705;10293.683921117688;10735.785284294427;10900.528428662346;11043;11103.72240693989;11126.554937802397;11170.050545703707;11208;11402.315861048364;11409.543618255177 06:00:21 INFO - PROCESS | 1953 | __end_tp_report 06:00:21 INFO - PROCESS | 1953 | __start_cc_report 06:00:21 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,1184 06:00:21 INFO - PROCESS | 1953 | __end_cc_report 06:00:21 INFO - PROCESS | 1953 | __startTimestamp1479045621327__endTimestamp 06:00:21 INFO - PROCESS | 1953 | 06:00:21 INFO - PROCESS | 1953 | ------- Summary: start ------- 06:00:21 INFO - PROCESS | 1953 | Number of tests: 6 06:00:21 INFO - PROCESS | 1953 | 06:00:21 INFO - PROCESS | 1953 | [#0] dojo.html Cycles:165 Average:5657.91 Median:5872.53 stddev:663.67 (11.3%) stddev-sans-first:628.52 06:00:21 INFO - PROCESS | 1953 | Values: 2865.9 3199.5 3203.4 3226.7 3234.1 5525.2 5535.1 5577.7 5621.9 5624.2 4774.8 5601.1 5648.4 5723.7 5780.7 5048.2 5131.3 5153.4 5186.2 5190.3 5197.6 5230.3 5254.1 5265.6 5266.8 5079.3 5224.9 5226.0 5267.2 5316.3 5148.3 5151.3 5168.3 5168.7 5185.3 5080.6 5139.6 5145.7 5147.4 5181.2 5600.7 5622.0 5623.9 5624.7 5633.5 6095.4 6096.0 6101.8 6104.9 6112.1 6103.4 6104.3 6169.8 6170.2 6185.5 5667.6 5775.2 5795.0 5822.9 5832.4 6196.2 6199.5 6199.8 6202.5 6209.7 5901.3 6120.4 6122.0 6125.7 6139.0 6111.2 6138.7 6144.3 6148.6 6157.4 5167.5 6134.4 6183.5 6186.0 6186.4 6167.2 6192.5 6211.3 6216.6 6219.3 4802.4 4854.6 4891.0 4904.1 4931.1 4302.8 4351.3 4361.0 4401.6 4424.1 5739.1 5741.7 5748.8 5751.2 5762.9 6106.4 6149.7 6155.3 6165.2 6169.7 5726.5 5727.4 5738.9 5748.9 5752.5 4620.3 5196.3 5291.2 5304.8 5311.4 6186.8 6248.3 6251.7 6254.9 6259.2 6232.3 6250.1 6258.9 6260.6 6273.2 6095.1 6179.7 6234.1 6250.2 6444.4 4740.9 6043.8 6084.8 6104.5 6116.4 4966.5 6205.3 6231.7 6241.9 6244.2 5999.7 6018.6 6033.5 6041.5 6043.3 5872.5 5925.6 5931.4 6047.1 6050.9 5759.5 5817.5 5824.4 5875.4 5902.6 5983.5 5993.2 6003.1 6066.6 6096.5 5860.9 5899.4 5918.6 5934.5 5944.2 06:00:21 INFO - PROCESS | 1953 | 06:00:21 INFO - PROCESS | 1953 | [#1] ext.html Cycles:180 Average:69882.84 Median:39546.91 stddev:154610.67 (391.0%) stddev-sans-first:154956.50 06:00:21 INFO - PROCESS | 1953 | Values: 447.3 450.5 452.2 494.1 503.9 29931.7 31857.9 32054.7 40049.0 40202.0 41376.6 41785.7 41814.4 41851.2 41887.2 9232.8 9417.5 9426.9 9433.9 9437.7 9331.6 9414.9 9451.4 9457.9 9460.2 9437.5 9477.3 9484.2 9484.2 9495.4 9386.9 9430.0 9435.6 9443.3 9475.8 9346.1 9387.6 9394.2 9394.3 9404.2 39402.6 39691.2 39816.8 39853.8 39892.8 3238.4 3259.6 3374.1 3422.3 3624.7 25353.9 29292.7 30198.7 30347.0 30350.4 2854.3 3105.4 3247.5 3276.4 3309.8 494855.0 495090.0 500879.0 501283.0 502463.5 59904.1 60356.7 60402.0 60425.4 60460.1 59146.7 59158.1 59201.4 59223.1 59243.7 15523.8 15777.0 15792.0 15803.0 15831.8 12070.9 12100.8 12134.2 12152.7 12152.8 16718.6 17889.1 18214.6 18268.4 18312.2 6038.4 7351.7 7483.2 7541.1 7582.9 881.7 948.4 987.9 1024.1 1038.7 29710.7 29731.7 29785.4 29833.6 29937.9 1085.3 1112.2 1119.7 1133.6 1149.8 15698.6 15991.4 16428.4 16601.3 16676.2 638861.0 643284.0 645341.0 646154.0 646813.0 56432.7 56908.0 56971.0 57005.7 57068.0 1327.7 1344.4 1364.5 1368.9 1371.5 28935.1 29538.6 29575.6 30551.7 30577.1 48773.5 49384.0 49389.3 49460.0 49463.8 574383.0 582033.1 582267.0 584261.0 584775.0 24641.9 24710.5 24794.0 24995.9 25032.7 43481.0 43545.3 43962.8 44210.3 44282.8 36251.3 36260.3 36987.4 37267.6 37685.8 35515.8 36003.0 36774.8 37096.6 37254.8 34969.0 35297.3 35629.9 35803.1 36205.0 30782.4 32161.5 32241.0 32246.5 32251.0 23665.9 23723.7 24058.5 24404.1 24414.6 06:00:21 INFO - PROCESS | 1953 | 06:00:21 INFO - PROCESS | 1953 | [#2] jquery.html Cycles:180 Average:33271.40 Median:24604.32 stddev:94988.12 (386.1%) stddev-sans-first:95222.42 06:00:21 INFO - PROCESS | 1953 | Values: 347.4 366.2 369.6 376.9 401.3 14164.4 14234.6 15404.3 19530.3 20168.5 15467.0 18768.1 19422.7 19938.5 19990.6 5389.7 5722.4 5750.7 5753.9 5760.9 5328.5 5529.5 5568.0 5587.0 5625.8 4966.9 5368.8 5537.3 5537.9 5541.1 5284.4 5496.9 5532.4 5560.4 5565.8 4884.0 4888.8 4889.6 4891.7 4894.5 24091.0 24428.5 24441.8 24476.4 24732.3 2192.3 2220.5 2295.5 2300.9 2323.2 14283.6 14434.7 14551.8 14709.4 15491.3 1744.3 1785.5 1829.5 1837.2 1867.0 109445.0 115020.0 115295.0 115562.0 115584.0 7756.9 9891.2 10198.1 10718.4 10971.0 12975.4 13005.8 13007.7 13010.5 13015.9 7934.0 8157.3 8225.6 8299.4 8312.9 7483.2 8183.9 8192.8 8337.7 8383.6 5422.4 6184.4 6325.3 6329.6 6336.3 1793.6 1807.8 1812.7 1820.2 1834.8 949.7 950.5 967.4 977.3 994.2 18061.5 21437.8 21452.8 21488.6 21506.9 893.3 921.4 974.9 981.2 993.9 9640.8 10762.6 11257.7 11468.9 11492.8 523885.0 568026.0 571193.0 592552.0 593127.0 112815.4 114452.4 115616.0 116229.4 117087.0 967.5 998.5 999.6 1000.4 1021.3 17693.0 18672.4 18726.3 18851.8 18974.6 43238.0 48706.1 50247.5 50333.2 51772.7 66862.7 70944.0 72317.0 74333.9 75205.1 7489.4 7523.8 7742.3 7756.8 7938.5 10019.3 10677.9 12384.6 13038.5 13095.5 11010.1 11072.2 11249.9 11287.2 11321.8 9115.4 9603.0 11351.3 11424.8 11545.3 11189.5 11326.0 11415.8 11590.1 11682.4 6595.8 7655.8 7778.9 7813.8 7907.4 10860.6 11150.2 11209.0 11257.7 11270.9 06:00:21 INFO - PROCESS | 1953 | 06:00:21 INFO - PROCESS | 1953 | [#3] mootools.html Cycles:180 Average:5188.02 Median:5077.79 stddev:2835.14 (55.8%) stddev-sans-first:2828.18 06:00:21 INFO - PROCESS | 1953 | Values: 1317.9 1447.8 1461.8 1632.3 1817.4 7275.5 7662.1 7989.2 8260.9 8270.1 7249.3 7998.9 8085.9 8169.2 8172.4 2207.3 2239.3 2246.7 2248.0 2249.0 2245.0 2290.0 2297.3 2298.1 2310.1 1854.6 1913.9 2228.1 2271.3 2283.0 2156.8 2191.2 2221.0 2223.5 2228.0 2236.5 2251.6 2253.2 2259.5 2259.9 7985.7 8164.6 8169.5 8173.9 8181.7 4854.7 5056.1 5066.2 5089.4 5090.8 3188.4 3391.3 3396.3 3448.3 3451.0 1000.1 1008.6 1049.9 1053.6 1068.4 10380.7 10425.9 10522.9 10534.4 10535.3 3981.5 4082.1 4144.7 4173.1 4285.2 7286.7 7700.4 8092.3 8447.2 8505.0 4412.3 4832.9 4960.0 4968.8 4973.2 3857.2 4199.1 4213.4 4329.8 4567.0 3510.6 3517.3 3520.9 3561.8 3563.6 1053.3 1054.9 1110.4 1123.6 1132.7 809.3 810.3 860.9 903.9 941.5 4907.5 4920.0 5466.7 5518.6 5526.9 845.7 882.4 896.0 901.0 910.5 3147.9 3758.4 3865.1 3867.9 3894.7 8866.8 9702.5 9855.9 9858.4 9884.2 8367.4 9491.2 9847.2 9856.0 9865.2 7333.4 7603.7 7608.8 7609.7 7618.9 4841.5 5308.1 5368.1 5443.3 5622.1 9025.9 9079.5 9096.7 9117.6 9209.1 7392.6 7714.9 9116.5 9244.7 9353.2 6364.4 6630.3 6636.8 6637.4 6638.5 7703.2 7719.8 7722.4 7796.3 7823.1 6524.3 6920.2 6925.4 6952.3 6973.0 6760.2 6813.1 6834.5 6871.6 6894.3 6463.9 6574.8 6580.8 6583.5 6584.3 4628.0 4725.9 4798.1 4847.7 4883.7 3921.6 4684.8 4856.0 4922.1 4987.6 06:00:21 INFO - PROCESS | 1953 | 06:00:21 INFO - PROCESS | 1953 | [#4] prototype.html Cycles:180 Average:4304.77 Median:4841.80 stddev:1309.35 (27.0%) stddev-sans-first:1278.00 06:00:21 INFO - PROCESS | 1953 | Values: 296.9 302.4 306.2 313.6 315.6 5158.2 5196.7 5224.8 5240.4 5250.7 5132.8 5169.1 5169.4 5184.7 5197.5 3762.8 3916.5 3927.0 3945.6 3956.7 3258.7 3511.7 3915.1 3931.3 3974.4 3311.2 3938.2 3947.9 3950.1 3951.9 3624.4 3968.0 3970.8 3982.8 3994.4 3259.8 3321.5 3403.9 3448.3 3469.5 5000.4 5021.2 5025.2 5048.1 5062.6 3604.6 3731.6 4457.3 4470.0 4517.1 4722.6 5113.2 5394.2 5428.3 5440.4 3778.7 3792.2 3797.4 3807.1 3815.0 5467.5 5574.6 5589.2 5748.2 5824.6 3148.3 3439.6 3842.5 3859.2 3887.4 3622.1 3910.2 3911.3 3952.9 3958.9 3725.6 4518.9 4551.8 4558.8 4572.6 5209.6 5480.6 5491.4 5526.4 5532.8 1076.8 1094.8 1257.9 1260.6 1266.1 830.2 842.8 880.2 890.8 891.7 4818.2 4838.9 4840.8 4869.6 4939.5 4842.8 4894.8 4988.9 5229.4 5256.3 4024.6 4396.1 4409.1 4564.5 4856.7 2370.6 2504.9 2596.4 2625.1 2818.8 4892.3 5456.3 5491.6 5516.3 5549.4 5060.6 5269.6 5329.8 5399.8 5422.8 4880.3 4908.8 4912.7 5470.5 5483.3 4626.7 4763.8 4837.2 5107.6 5359.0 4825.5 4970.4 5223.4 5226.8 5276.1 4589.0 4768.7 4778.5 5035.4 5479.5 3038.5 3287.9 3724.4 3848.3 3857.4 4421.9 5206.8 5348.9 5682.7 5691.3 5177.5 5233.8 5386.8 5454.5 5491.8 5375.9 5411.8 5424.0 5444.9 5445.6 4738.9 4981.5 5311.4 5332.3 5371.2 4624.5 4742.8 5066.0 5433.5 5450.5 4472.7 4531.8 4883.4 4900.3 5139.8 06:00:21 INFO - PROCESS | 1953 | 06:00:21 INFO - PROCESS | 1953 | [#5] yui.html Cycles:180 Average:19934.93 Median:4608.19 stddev:29080.76 (631.1%) stddev-sans-first:29126.42 06:00:21 INFO - PROCESS | 1953 | Values: 684.9 726.3 751.3 782.6 794.7 14492.5 15012.1 15057.3 15116.8 15118.7 13353.5 13496.9 13500.1 13515.7 13528.4 645.6 673.8 674.1 795.8 820.6 835.1 839.9 841.6 844.3 846.0 899.7 900.5 901.3 901.9 904.0 898.5 899.2 900.1 900.1 901.4 513.5 527.9 640.4 651.7 667.5 13955.4 13978.0 13996.4 14003.9 14012.3 12813.5 12984.3 13201.8 13214.5 13414.9 10080.2 11721.9 11951.5 12004.8 12464.9 4353.3 4381.6 4449.4 4544.5 4551.7 86127.1 86964.3 87200.0 88896.7 91095.5 9402.3 10331.2 10399.1 10420.9 10739.7 19719.2 19948.9 19956.9 19964.3 20166.8 6744.8 7012.3 7147.5 7168.8 7358.8 4145.4 4166.4 4221.1 4231.1 4336.4 3035.2 3129.2 3129.6 3132.7 3139.1 583.4 586.0 614.1 634.6 634.7 524.4 528.2 582.7 594.5 615.7 8121.3 8290.4 8379.2 8474.4 8748.8 477.3 554.0 567.1 574.3 610.7 4047.0 4581.4 4635.0 4660.9 4777.0 114768.0 115197.0 115791.0 116100.0 116180.0 60239.4 60778.0 61802.7 62125.0 81337.2 69108.0 89533.6 90619.0 91037.0 92998.0 9198.5 9244.7 9265.7 9298.9 9326.0 55371.5 55604.4 55769.7 56360.7 56718.7 61757.0 68299.0 78383.0 81868.2 83008.0 11708.7 12259.4 12296.4 12333.0 12553.9 13988.9 14350.4 14360.4 14517.8 14672.3 10632.8 13049.9 13198.0 13259.7 13595.3 11593.2 12617.7 13040.2 13053.1 13950.0 10949.2 11653.3 12525.9 12626.7 13356.5 10293.7 10735.8 10900.5 11043.0 11103.7 11126.6 11170.1 11208.0 11402.3 11409.5 06:00:21 INFO - PROCESS | 1953 | -------- Summary: end -------- 06:00:21 INFO - PROCESS | 1953 | 06:00:21 INFO - TEST-INFO | 1953: exit 0 06:00:21 INFO - TEST-OK | dromaeo_css | took 1120187ms 06:00:21 INFO - TEST-START | kraken 06:00:21 INFO - operating with platform_type : mac_ 06:00:21 INFO - Initialising browser for kraken test... 06:00:21 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpRt0k9z/profile http://localhost:52493/getInfo.html) 06:00:24 INFO - PROCESS | 1955 | __metrics Screen width/height:1600/1200 06:00:24 INFO - PROCESS | 1955 | colorDepth:24 06:00:24 INFO - PROCESS | 1955 | Browser inner width/height: 1024/689 06:00:24 INFO - PROCESS | 1955 | __metrics 06:00:24 INFO - PROCESS | 1955 | 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" 06:00:24 INFO - PROCESS | 1955 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:00:25 INFO - TEST-INFO | 1955: exit 0 06:00:25 INFO - Browser initialized. 06:00:25 INFO - Running cycle 1/1 for kraken test... 06:00:25 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpRt0k9z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:00:37 INFO - PROCESS | 1957 | RSS: Main: 230518784 06:00:37 INFO - PROCESS | 1957 | 06:00:40 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52493/tests/kraken/driver.html?testName=audio-beat-detection) 06:00:41 INFO - PROCESS | 1957 | RSS: Main: 288391168 06:00:41 INFO - PROCESS | 1957 | 06:00:43 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52493/tests/kraken/driver.html?testName=audio-dft) 06:00:44 INFO - PROCESS | 1957 | RSS: Main: 289665024 06:00:44 INFO - PROCESS | 1957 | 06:00:48 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52493/tests/kraken/driver.html?testName=audio-fft) 06:00:48 INFO - PROCESS | 1957 | RSS: Main: 382492672 06:00:48 INFO - PROCESS | 1957 | 06:00:50 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52493/tests/kraken/driver.html?testName=audio-oscillator) 06:00:51 INFO - PROCESS | 1957 | RSS: Main: 283226112 06:00:51 INFO - PROCESS | 1957 | 06:00:53 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52493/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:00:53 INFO - PROCESS | 1957 | RSS: Main: 287932416 06:00:53 INFO - PROCESS | 1957 | 06:00:56 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52493/tests/kraken/driver.html?testName=imaging-darkroom) 06:00:57 INFO - PROCESS | 1957 | RSS: Main: 330342400 06:00:57 INFO - PROCESS | 1957 | 06:01:00 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52493/tests/kraken/driver.html?testName=imaging-desaturate) 06:01:00 INFO - PROCESS | 1957 | RSS: Main: 330723328 06:01:00 INFO - PROCESS | 1957 | 06:01:03 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52493/tests/kraken/driver.html?testName=json-parse-financial) 06:01:04 INFO - PROCESS | 1957 | RSS: Main: 328007680 06:01:04 INFO - PROCESS | 1957 | 06:01:05 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52493/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:01:06 INFO - PROCESS | 1957 | RSS: Main: 303333376 06:01:06 INFO - PROCESS | 1957 | 06:01:08 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:01:09 INFO - PROCESS | 1957 | RSS: Main: 306221056 06:01:09 INFO - PROCESS | 1957 | 06:01:11 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:01:11 INFO - PROCESS | 1957 | RSS: Main: 308465664 06:01:11 INFO - PROCESS | 1957 | 06:01:14 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:01:15 INFO - PROCESS | 1957 | RSS: Main: 326320128 06:01:15 INFO - PROCESS | 1957 | 06:01:18 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:01:19 INFO - PROCESS | 1957 | RSS: Main: 319913984 06:01:19 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52493/tests/kraken/driver.html?testName=ai-astar) 06:01:21 INFO - PROCESS | 1957 | RSS: Main: 363827200 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | __start_tp_report 06:01:21 INFO - PROCESS | 1957 | _x_x_mozilla_page_load 06:01:21 INFO - PROCESS | 1957 | _x_x_mozilla_page_load_details 06:01:21 INFO - PROCESS | 1957 | |i|pagename|runs| 06:01:21 INFO - PROCESS | 1957 | |0;ai-astar;103;113;113;112;112;114;115;97;153;167 06:01:21 INFO - PROCESS | 1957 | |1;audio-beat-detection;146;113;113;149;111;111;112;124;139;111 06:01:21 INFO - PROCESS | 1957 | |2;audio-dft;185;185;184;188;181;195;261;181;180;184 06:01:21 INFO - PROCESS | 1957 | |3;audio-fft;81;60;58;90;62;63;58;59;83;59 06:01:21 INFO - PROCESS | 1957 | |4;audio-oscillator;85;70;93;69;68;70;89;67;68;92 06:01:21 INFO - PROCESS | 1957 | |5;imaging-gaussian-blur;87;85;91;128;121;85;88;86;87;85 06:01:21 INFO - PROCESS | 1957 | |6;imaging-darkroom;92;93;94;95;92;93;94;97;94;93 06:01:21 INFO - PROCESS | 1957 | |7;imaging-desaturate;83;84;81;80;82;84;79;86;81;80 06:01:21 INFO - PROCESS | 1957 | |8;json-parse-financial;56;54;55;54;49;55;54;54;55;54 06:01:21 INFO - PROCESS | 1957 | |9;json-stringify-tinderbox;66;73;69;77;74;72;72;72;72;72 06:01:21 INFO - PROCESS | 1957 | |10;stanford-crypto-aes;88;87;87;88;84;86;77;98;97;108 06:01:21 INFO - PROCESS | 1957 | |11;stanford-crypto-ccm;148;149;165;162;154;163;159;162;196;163 06:01:21 INFO - PROCESS | 1957 | |12;stanford-crypto-pbkdf2;175;171;173;193;244;227;170;176;172;176 06:01:21 INFO - PROCESS | 1957 | |13;stanford-crypto-sha256-iterative;68;68;69;64;87;67;68;67;66;68 06:01:21 INFO - PROCESS | 1957 | __end_tp_report 06:01:21 INFO - PROCESS | 1957 | __start_cc_report 06:01:21 INFO - PROCESS | 1957 | _x_x_mozilla_cycle_collect,1996 06:01:21 INFO - PROCESS | 1957 | __end_cc_report 06:01:21 INFO - PROCESS | 1957 | __startTimestamp1479045681410__endTimestamp 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | ------- Summary: start ------- 06:01:21 INFO - PROCESS | 1957 | Number of tests: 14 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | [#0] ai-astar Cycles:10 Average:119.90 Median:114.50 stddev:22.12 (19.3%) stddev-sans-first:22.60 06:01:21 INFO - PROCESS | 1957 | Values: 103.0 113.0 113.0 112.0 112.0 114.0 115.0 97.0 153.0 167.0 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | [#1] audio-beat-detection Cycles:10 Average:122.90 Median:118.50 stddev:15.69 (13.2%) stddev-sans-first:14.24 06:01:21 INFO - PROCESS | 1957 | Values: 146.0 113.0 113.0 149.0 111.0 111.0 112.0 124.0 139.0 111.0 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | [#2] audio-dft Cycles:10 Average:192.40 Median:185.00 stddev:24.49 (13.2%) stddev-sans-first:25.83 06:01:21 INFO - PROCESS | 1957 | Values: 185.0 185.0 184.0 188.0 181.0 195.0 261.0 181.0 180.0 184.0 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | [#3] audio-fft Cycles:10 Average:67.30 Median:62.50 stddev:12.29 (19.7%) stddev-sans-first:12.00 06:01:21 INFO - PROCESS | 1957 | Values: 81.0 60.0 58.0 90.0 62.0 63.0 58.0 59.0 83.0 59.0 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:77.50 stddev:11.12 (14.3%) stddev-sans-first:11.42 06:01:21 INFO - PROCESS | 1957 | Values: 85.0 70.0 93.0 69.0 68.0 70.0 89.0 67.0 68.0 92.0 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | [#5] imaging-gaussian-blur Cycles:10 Average:94.30 Median:86.50 stddev:16.10 (18.6%) stddev-sans-first:16.86 06:01:21 INFO - PROCESS | 1957 | Values: 87.0 85.0 91.0 128.0 121.0 85.0 88.0 86.0 87.0 85.0 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:94.00 stddev:1.49 (1.6%) stddev-sans-first:1.45 06:01:21 INFO - PROCESS | 1957 | Values: 92.0 93.0 94.0 95.0 92.0 93.0 94.0 97.0 94.0 93.0 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | [#7] imaging-desaturate Cycles:10 Average:82.00 Median:82.50 stddev:2.21 (2.7%) stddev-sans-first:2.32 06:01:21 INFO - PROCESS | 1957 | Values: 83.0 84.0 81.0 80.0 82.0 84.0 79.0 86.0 81.0 80.0 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.50 stddev:1.89 (3.5%) stddev-sans-first:1.86 06:01:21 INFO - PROCESS | 1957 | Values: 56.0 54.0 55.0 54.0 49.0 55.0 54.0 54.0 55.0 54.0 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:72.00 stddev:2.88 (4.0%) stddev-sans-first:2.13 06:01:21 INFO - PROCESS | 1957 | Values: 66.0 73.0 69.0 77.0 74.0 72.0 72.0 72.0 72.0 72.0 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:87.50 stddev:8.72 (10.0%) stddev-sans-first:9.22 06:01:21 INFO - PROCESS | 1957 | Values: 88.0 87.0 87.0 88.0 84.0 86.0 77.0 98.0 97.0 108.0 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | [#11] stanford-crypto-ccm Cycles:10 Average:162.10 Median:162.50 stddev:13.35 (8.2%) stddev-sans-first:13.15 06:01:21 INFO - PROCESS | 1957 | Values: 148.0 149.0 165.0 162.0 154.0 163.0 159.0 162.0 196.0 163.0 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.70 Median:176.00 stddev:26.31 (15.0%) stddev-sans-first:27.51 06:01:21 INFO - PROCESS | 1957 | Values: 175.0 171.0 173.0 193.0 244.0 227.0 170.0 176.0 172.0 176.0 06:01:21 INFO - PROCESS | 1957 | 06:01:21 INFO - PROCESS | 1957 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:68.00 stddev:6.41 (9.4%) stddev-sans-first:6.78 06:01:21 INFO - PROCESS | 1957 | Values: 68.0 68.0 69.0 64.0 87.0 67.0 68.0 67.0 66.0 68.0 06:01:21 INFO - PROCESS | 1957 | -------- Summary: end -------- 06:01:21 INFO - PROCESS | 1957 | 06:01:22 INFO - TEST-INFO | 1957: exit 0 06:01:22 INFO - TEST-OK | kraken | took 60344ms 06:01:22 INFO - SUITE-END | took 1180s 06:01:22 INFO - Completed test suite (00:19:40) 06:01:22 INFO - javascript benchmark 06:01:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2865.853658536585, 3199.456092464281, 3203.407369636617, 3226.7257283130934, 3234.1267857678426, 5525.198846167304, 5535.14205298179, 5577.721113944302, 5621.887562248752, 5624.15637654352, 4774.7851346689395, 5601.131824567188, 5648.435156484349, 5723.713814309278, 5780.682062486561, 5048.242763585455, 5131.281620573122, 5153.381594208681, 5186.247994040862, 5190.299309593194, 5197.636165468419, 5230.34620672416, 5254.106801843695, 5265.605079619025, 5266.81566145185, 5079.339685840854, 5224.895502089937, 5225.973870130625, 5267.209918512215, 5316.282301889234, 5148.253503242009, 5151.304573882516, 5168.3281173447585, 5168.689878607295, 5185.274061631375, 5080.618953578496, 5139.58883289336, 5145.665531740462, 5147.433782283946, 5181.1969144782615, 5600.691961942091, 5622.0, 5623.915641265385, 5624.690642014688, 5633.464820842013, 6095.420935011167, 6096.0, 6101.786437474667, 6104.877902441926, 6112.083187521863, 6103.435603539908, 6104.307579233822, 6169.784057557964, 6170.167027451282, 6185.474234690012, 5667.603267771257, 5775.220345253421, 5795.043817770021, 5822.912656310116, 5832.4459176378605, 6196.1635179250225, 6199.535034872402, 6199.752009919643, 6202.503799696014, 6209.720562574695, 5901.29184497863, 6120.357362476965, 6122.0, 6125.663088530174, 6138.969305153446, 6111.236095488064, 6138.7237574309265, 6144.279601711528, 6148.569600128038, 6157.353477884847, 5167.457416971239, 6134.447899689049, 6183.536234782409, 6186.0, 6186.443220110212, 6167.198264225622, 6192.504599632019, 6211.3167551569295, 6216.564840461215, 6219.284782250009, 4802.423709154924, 4854.611631069506, 4891.0, 4904.141775189328, 4931.112399768076, 4302.806373713191, 4351.282038463682, 4360.978195109004, 4401.559844015637, 4424.13729322779, 5739.081746920473, 5741.655500669973, 5748.798792042342, 5751.22358481608, 5762.94237057624, 6106.39999602577, 6149.66176860268, 6155.322914479404, 6165.229346331708, 6169.691515424211, 5726.541876649858, 5727.3699893011735, 5738.885222295531, 5748.942510574924, 5752.539796816244, 4620.306953956933, 5196.32447781786, 5291.1798671206025, 5304.814331498379, 5311.442298558596, 6186.752529898754, 6248.312685604489, 6251.718672659832, 6254.906176407358, 6259.1550140730415, 6232.345603711727, 6250.093736408105, 6258.937410625836, 6260.561760676709, 6273.1844860167885, 6095.05526643371, 6179.721912513858, 6234.096056071753, 6250.218722659668, 6444.387783160592, 4740.905181896412, 6043.8186854394435, 6084.756609735561, 6104.481119104926, 6116.35778243278, 4966.528179822911, 6205.317415084247, 6231.719572619152, 6241.906371404523, 6244.188255526752, 5999.730012149376, 6018.608790428608, 6033.487153591994, 6041.516678665608, 6043.274807023009, 5872.530197584098, 5925.555583331181, 5931.436513531207, 6047.0627052806885, 6050.878982420239, 5759.539236860957, 5817.534597232296, 5824.388439213991, 5875.41245875409, 5902.557308201816, 5983.4914032306, 5993.190919226023, 6003.129546215861, 6066.636001839904, 6096.481799046954, 5860.853478663067, 5899.439553242435, 5918.644881307136, 5934.495567876606, 5944.197533332945], "value": 5613.715802933767, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [447.3178402935523, 450.45494951109157, 452.2312069481878, 494.0884068892894, 503.8891443882346, 29931.700682993163, 31857.884974025914, 32054.67945320546, 40048.99877503064, 40201.99495012626, 41376.58623413765, 41785.74642760715, 41814.37278440818, 41851.162976740445, 41887.16225675492, 9232.769180770467, 9417.529123543829, 9426.858597121049, 9433.858492122623, 9437.716868493957, 9331.626734930594, 9414.905850941506, 9451.432914025147, 9457.858132128023, 9460.24318054554, 9437.480938548377, 9477.336586438953, 9484.19384352331, 9484.241260699162, 9495.430274183536, 9386.906130938705, 9429.952850235773, 9435.622575096988, 9443.338966272364, 9475.810483790354, 9346.0653934606, 9387.57755900986, 9394.181745476399, 9394.295427842939, 9404.200642945358, 39402.60597394018, 39691.20617587632, 39816.800915995525, 39853.80073099645, 39892.80053599743, 3238.3685181389637, 3259.5762550868235, 3374.1395944034116, 3422.3155368926314, 3624.7100231981376, 25353.87323063391, 29292.70707292921, 30198.69801301981, 30347.0, 30350.392992140256, 2854.272160599034, 3105.394448082625, 3247.5453436518906, 3276.3611095836322, 3309.7683162178655, 494855.0, 495090.0, 500879.0, 501282.9871701273, 502463.48768255924, 59904.101438477584, 60356.69821650864, 60402.0, 60425.39574604286, 60460.09309860268, 59146.70426647925, 59158.11262831061, 59201.40798592045, 59223.111653325235, 59243.70378148081, 15523.767143492856, 15777.0, 15792.0, 15803.0, 15831.762523562156, 12070.879291207151, 12100.757984840255, 12134.211276267191, 12152.69618259533, 12152.817707734392, 16718.58203544921, 17889.10554472271, 18214.63570728578, 18268.360607378945, 18312.161614949706, 6038.396160384014, 7351.705931762776, 7483.214262502468, 7541.058986995076, 7582.924170758332, 881.7266647339345, 948.3598570964709, 987.8814542254976, 1024.08856118055, 1038.6883934819523, 29710.69670682974, 29731.702682972893, 29785.40429191404, 29833.64525881548, 29937.85031074874, 1085.2891356161726, 1112.193659596802, 1119.7200699825044, 1133.5692436873935, 1149.8275258711094, 15698.607534811721, 15991.440299589458, 16428.38575513385, 16601.335946562245, 16676.249568769435, 638861.0, 643284.0, 645341.0, 646154.0, 646813.0, 56432.71783641056, 56908.0, 56971.0, 57005.71497142571, 57068.0, 1327.7410904873457, 1344.4286178374152, 1364.5087768403366, 1368.8631136886231, 1371.5062577472102, 28935.131946041653, 29538.556921645762, 29575.556366654087, 30551.694483054885, 30577.08268751941, 48773.512264878314, 49384.0, 49389.25916111333, 49460.0, 49463.752681236365, 574383.0, 582033.0898345481, 582267.0, 584261.0, 584775.0, 24641.87679061665, 24710.50578988374, 24794.008239670213, 24995.87502062478, 25032.749672503043, 43481.0, 43545.346819797094, 43962.78018609886, 44210.336844948, 44282.77858610686, 36251.27497449984, 36260.274794503435, 36987.36213007148, 37267.62732372642, 37685.81157094197, 35515.82242088876, 36003.0, 36774.81612592027, 37096.6290337104, 37254.8137259312, 34969.0, 35297.29405411929, 35629.93110206698, 35803.10492237715, 36205.0, 30782.384352313275, 32161.517577235893, 32241.03276901697, 32246.516302255015, 32251.0, 23665.881670591538, 23723.71325086312, 24058.51882962366, 24404.145854895705, 24414.633780493663], "value": 19444.272914025787, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.3816606440537, 366.15966357210203, 369.5565321614062, 376.88881779874936, 401.3357892687601, 14164.362603682834, 14234.572962811118, 15404.306806193707, 19530.316438924605, 20168.495787605316, 15467.0, 18768.061596920168, 19422.70865937012, 19938.501537461572, 19990.60018799623, 5389.73051347432, 5722.399148089453, 5750.654960702351, 5753.884922301551, 5760.855978600526, 5328.520433161015, 5529.474699903523, 5567.9977604031255, 5586.972065139668, 5625.80309689161, 4966.850994470154, 5368.785248590052, 5537.280153580029, 5537.916931246034, 5541.141123125902, 5284.4187139414635, 5496.862578435571, 5532.3637781655025, 5560.388357280696, 5565.777368905239, 4883.951160488421, 4888.755562221911, 4889.559939605417, 4891.657583969123, 4894.486078961693, 24091.0, 24428.511429771304, 24441.755582444304, 24476.388090297885, 24732.258032259062, 2192.2765487389124, 2220.4781876259067, 2295.5064661097945, 2300.8734519601417, 2323.1752727781563, 14283.64290892736, 14434.711305773932, 14551.78172327416, 14709.411623535047, 15491.302891369915, 1744.302279088375, 1785.4554360919915, 1829.4603092087866, 1837.2191818477095, 1867.0384751852807, 109445.0, 115019.99999999916, 115295.0, 115562.0, 115584.0, 7756.883646745303, 9891.208703303719, 10198.082172604501, 10718.41048742311, 10971.0, 12975.404919016235, 13005.804912926315, 13007.739845203232, 13010.479580816662, 13015.869841301655, 7933.9603301983125, 8157.347412207009, 8225.642768943177, 8299.377546683902, 8312.8753068704, 7483.214262502468, 8183.877241841377, 8192.75421737349, 8337.749867503984, 8383.580820958927, 5422.43064478232, 6184.3920580648455, 6325.272593651697, 6329.620222786648, 6336.303006669263, 1793.641271745656, 1807.8101799310882, 1812.6555954368628, 1820.2445984916112, 1834.798242109474, 949.7008442340641, 950.4914870544226, 967.3808762392075, 977.2621670638622, 994.1897353656747, 18061.548461288574, 21437.785622143892, 21452.78547214539, 21488.57022859534, 21506.892465537887, 893.3155866620763, 921.4102974096584, 974.936629119105, 981.16600889245, 993.8807343118872, 9640.807183856283, 10762.56949722018, 11257.662270131908, 11468.885311146782, 11492.770144597062, 523885.0, 568026.0, 571193.0, 592552.0, 593127.0, 112815.4359228215, 114452.42773786078, 115616.0, 116229.41885290689, 117087.0, 967.458223394887, 998.4608311511847, 999.6001599360169, 1000.4047591682937, 1021.3208216536069, 17693.0, 18672.439826805217, 18726.250949962396, 18851.811481885554, 18974.620507589494, 43238.0, 48706.05175792929, 50247.49752502428, 50333.24500132428, 51772.74113629408, 66862.66568667126, 70944.0, 72317.0, 74333.88499172626, 75205.11987200363, 7489.438292128221, 7523.8495230093995, 7742.303192712683, 7756.767296981099, 7938.5236885787035, 10019.348742331726, 10677.878822723502, 12384.56654017105, 13038.47846086146, 13095.541656041993, 11010.064144547483, 11072.20875602228, 11249.887501124884, 11287.20989530725, 11321.7735645285, 9115.407498512575, 9603.03969603034, 11351.318920864775, 11424.771504570028, 11545.307281563133, 11189.496472658588, 11326.037286830382, 11415.771684566429, 11590.130740194687, 11682.357470338873, 6595.769148079794, 7655.846883062419, 7778.922210778046, 7813.843723125619, 7907.446478746432, 10860.619878304538, 11150.219484635998, 11209.0, 11257.662270131908, 11270.887291127312], "value": 9320.175117384453, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.901157413193, 1447.7683570628699, 1461.7588097963833, 1632.3389027443889, 1817.4365946556568, 7275.454340924425, 7662.118856331524, 7989.201079892001, 8260.876086858701, 8270.131636178205, 7249.3113154250395, 7998.920145780333, 8085.91914080859, 8169.183081691843, 8172.427930044884, 2207.315732123043, 2239.2722365231366, 2246.6742322363248, 2248.022110381981, 2248.9662655060188, 2245.0346351069034, 2289.954200915981, 2297.27635794725, 2298.080767692928, 2310.06442390832, 1854.6198029404006, 1913.913873875672, 2228.0530774420886, 2271.250487339182, 2282.9657555136605, 2156.773538778429, 2191.233068426054, 2220.9888950555146, 2223.488597622556, 2228.0419419649543, 2236.4632488202715, 2251.5609456156058, 2253.2113760183806, 2259.4690247791755, 2259.920902768395, 7985.720499782537, 8164.591770411455, 8169.5098294102545, 8173.877391839127, 8181.71364002263, 4854.660173787836, 5056.140456122468, 5066.164082926312, 5089.4147173074825, 5090.796368145269, 3188.3942051010235, 3391.3217356528794, 3396.320735852815, 3448.327576122657, 3451.033710561022, 1000.1248907206194, 1008.5915204342253, 1049.9160067194607, 1053.615430367918, 1068.358984609236, 10380.740481487948, 10425.895741042568, 10522.947705229491, 10534.367937923673, 10535.315204511682, 3981.5421226559315, 4082.0611259410502, 4144.668426525931, 4173.08192197716, 4285.164392943346, 7286.7085316586745, 7700.383969282444, 8092.312153467021, 8447.239748422671, 8504.957475212585, 4412.338149277634, 4832.888435659818, 4959.950400496021, 4968.826091086795, 4973.229149481836, 3857.1707082977296, 4199.118185181085, 4213.410122582841, 4329.848455304049, 4566.977165114153, 3510.578730552299, 3517.296540691872, 3520.9471857922154, 3561.821908904544, 3563.6436356364675, 1053.2890299048113, 1054.920880933933, 1110.4225802582612, 1123.5842738186923, 1132.671525257666, 809.2595275323014, 810.3112354498629, 860.9009963854112, 903.9322050846213, 941.5433514745343, 4907.533784290486, 4920.0, 5466.6993315367245, 5518.586106042063, 5526.92225016118, 845.691322667222, 882.4440602420434, 895.9776005599782, 900.9684661036832, 910.5219759626117, 3147.874085036573, 3758.379867321861, 3865.0530619997717, 3867.941980870289, 3894.727369084137, 8866.778330541789, 9702.51487425626, 9855.852162217572, 9858.359206651545, 9884.209263258923, 8367.414281000272, 9491.193248573949, 9847.212223022141, 9856.0, 9865.210783137332, 7333.449991250677, 7603.7338693146585, 7608.80977975544, 7609.733659322008, 7618.923810761821, 4841.467438581823, 5308.124159513637, 5368.087425137658, 5443.265159203615, 5622.100463925753, 9025.909740902769, 9079.45523268606, 9096.681616143404, 9117.589708463267, 9209.079092090737, 7392.58779801299, 7714.884276735741, 9116.544172791466, 9244.676436324697, 9353.20497757671, 6364.363563643598, 6630.336966303524, 6636.800895973129, 6637.369449902251, 6638.468922486093, 7703.2296770324765, 7719.807004824925, 7722.420818438527, 7796.298333150044, 7823.100343460347, 6524.347565243438, 6920.238773734985, 6925.411340036153, 6952.270011648704, 6972.965135174291, 6760.18877734675, 6813.080234168522, 6834.45324374039, 6871.622060786504, 6894.276101009322, 6463.903041454476, 6574.80275591733, 6580.80257592273, 6583.539152259296, 6584.275729669828, 4627.9768601156775, 4725.9054818904115, 4798.1123492154065, 4847.733374664427, 4883.706977581356, 3921.5686274510354, 4684.8360307389075, 4855.95144048555, 4922.114019476386, 4987.60099192071], "value": 4212.84509117346, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.8931184773482, 302.40275455974455, 306.2481607653211, 313.6424476097248, 315.5708236797956, 5158.174692049273, 5196.6622169559, 5224.764885580148, 5240.397354304256, 5250.684958902469, 5132.769025393857, 5169.148984006911, 5169.405518365389, 5184.662996905198, 5197.454267301936, 3762.758289764371, 3916.454304701938, 3927.0, 3945.60543945606, 3956.6834653227675, 3258.657840926704, 3511.6839484446396, 3915.138669492707, 3931.272714547818, 3974.4237085622713, 3311.172206948263, 3938.212357528506, 3947.9210415791667, 3950.091478959841, 3951.8857411284966, 3624.420092785168, 3968.0, 3970.801459927021, 3982.761034337949, 3994.4407782910416, 3259.8044117353033, 3321.501774733785, 3403.910748560462, 3448.2758620689683, 3469.5142680024815, 5000.399952005746, 5021.246812978046, 5025.195968644999, 5048.14181589127, 5062.594992400636, 3604.6395360464007, 3731.589525152231, 4457.264551349023, 4469.977650111761, 4517.119161763458, 4722.6221902247735, 5113.162194981784, 5394.244805727201, 5428.344354971732, 5440.415802493784, 3778.6572325158304, 3792.2036372361836, 3797.39241721326, 3807.1053302473897, 3815.04623844039, 5467.480589344006, 5574.637648552791, 5589.189567512688, 5748.240844394672, 5824.563157763185, 3148.307372378073, 3439.6044454887506, 3842.538895332522, 3859.1702783901596, 3887.3974533947285, 3622.130688634709, 3910.237503686754, 3911.3155197840265, 3952.881413557598, 3958.8614398495915, 3725.5529336479794, 4518.864434066983, 4551.795169217393, 4558.817647294138, 4572.565606267466, 5209.583233341324, 5480.6437581557075, 5491.36849262332, 5526.4197259288, 5532.75102620383, 1076.8007918535095, 1094.819354806464, 1257.9433925473193, 1260.6470188347278, 1266.1326991011188, 830.2320353672949, 842.839860426517, 880.190224993008, 890.7505898348472, 891.6522556203086, 4818.229083346648, 4838.879028024257, 4840.806367745251, 4869.561739443467, 4939.456659767423, 4842.789302674331, 4894.828680996148, 4988.902441462872, 5229.424763276036, 5256.26412302278, 4024.63778259958, 4396.142752163298, 4409.112031619295, 4564.452265728134, 4856.708597484162, 2370.63255195445, 2504.8622325771894, 2596.4417650205296, 2625.146827281103, 2818.8167769094944, 4892.339534162914, 5456.263404440429, 5491.560675145979, 5516.3380394352935, 5549.366279156869, 5060.595152387874, 5269.578433725293, 5329.760160792696, 5399.83800485986, 5422.783088676409, 4880.267959806058, 4908.803647854117, 4912.70523768575, 5470.452954704578, 5483.341998960071, 4626.653001024869, 4763.7856296467435, 4837.234409440599, 5107.616928730435, 5358.97320513395, 4825.469198388104, 4970.378702662168, 5223.373195216598, 5226.843194704165, 5276.148411659433, 4589.0, 4768.737719425465, 4778.450478195052, 5035.446100928822, 5479.479449452296, 3038.468268053126, 3287.868485260563, 3724.42271447926, 3848.2688289226076, 3857.4213867920034, 4421.889452763707, 5206.765695543634, 5348.919766203432, 5682.687452190004, 5691.26013618227, 5177.456367081402, 5233.816816411408, 5386.811461598825, 5454.536364409069, 5491.835244942658, 5375.919361209663, 5411.83764487066, 5424.0, 5444.891102178013, 5445.618806683494, 4738.929345600175, 4981.501849814989, 5311.3891902431715, 5332.333833083459, 5371.248025276543, 4624.514425985223, 4742.762861856962, 5065.985738485595, 5433.505785908907, 5450.509454149145, 4472.664550158686, 4531.773411329486, 4883.389576302962, 4900.289458028495, 5139.820106296262], "value": 3888.8267910006252, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.900689400037, 726.2846096594857, 751.2862780358661, 782.6065448069452, 794.7059587952458, 14492.492762753305, 15012.099274043565, 15057.322420491077, 15116.848831511696, 15118.697626047486, 13353.465861365557, 13496.865031349684, 13500.122492038035, 13515.729685406286, 13528.391222394992, 645.6029541831763, 673.787756856591, 674.1404708996045, 795.8209402884333, 820.5774026376421, 835.1356346181703, 839.9244068033876, 841.6338892581716, 844.2992316377423, 845.9788505287386, 899.6896070855545, 900.4957223954574, 901.342020325163, 901.9323550733689, 904.0055938467699, 898.5237823953282, 899.1817446124029, 900.0504467786483, 900.0909081827336, 901.4140808474478, 513.453172371427, 527.9181726832347, 640.4044238857861, 651.6611362091686, 667.502710480695, 13955.372008259652, 13978.0, 13996.370163342572, 14003.859961400358, 14012.299385030708, 12813.487460501568, 12984.28586427746, 13201.801972970317, 13214.471421143142, 13414.932925335408, 10080.193584513221, 11721.88278117225, 11951.521939122425, 12004.759904801942, 12464.875351246463, 4353.25994580922, 4381.561843815625, 4449.399331090293, 4544.500104988449, 4551.65862560306, 86127.13872861254, 86964.26071478598, 87200.0, 88896.65746090903, 91095.54452227763, 9402.294827887936, 10331.173506119492, 10399.064084232456, 10420.947895260475, 10739.731506712395, 19719.211231550576, 19948.90025549892, 19956.90021549912, 19964.30124945649, 20166.798332016493, 6744.831379215559, 7012.263712310134, 7147.517144180437, 7168.784936451915, 7358.816029599303, 4145.440365550612, 4166.375043743463, 4221.071364299849, 4231.090315582103, 4336.436263285753, 3035.1501579557757, 3129.186411532972, 3129.6244450666068, 3132.7493800496363, 3139.089663997461, 583.3699604427209, 585.9765609375662, 614.1494030767419, 634.5970308853819, 634.6541135081392, 524.4126578232405, 528.2419727690776, 582.6911736779514, 594.4560726934806, 615.7075389190098, 8121.309688676529, 8290.37822163328, 8379.162083791573, 8474.364422668325, 8748.781280467912, 477.28169105496005, 553.9972300138473, 567.140781715701, 574.2678085441013, 610.7343305661963, 4047.0, 4581.381513495702, 4635.0, 4660.930086048712, 4777.0, 114768.0, 115197.0, 115791.0, 116100.0, 116180.0, 60239.397606023376, 60778.0, 61802.69098654478, 62125.0, 81337.18662813415, 69108.0, 89533.55233223923, 90619.0, 91037.0, 92997.99999999865, 9198.49408282551, 9244.722658320261, 9265.675701350421, 9298.860517092384, 9326.0, 55371.5082821266, 55604.443955559924, 55769.721151393984, 56360.71819640876, 56718.716406417705, 61757.0, 68299.0, 78383.0, 81868.18131818844, 83008.0, 11708.722431949069, 12259.448324825582, 12296.385180740748, 12333.032738287975, 12553.874461255271, 13988.860111399163, 14350.354234059283, 14360.353784079532, 14517.78223326672, 14672.3397447113, 10632.787344252916, 13049.869501305246, 13198.0, 13259.734805304033, 13595.252261125734, 11593.24643898144, 12617.747645047231, 13040.217586944813, 13053.078598593289, 13950.0, 10949.233553651167, 11653.30080195191, 12525.93737031309, 12626.747465050465, 13356.532521361705, 10293.683921117688, 10735.785284294427, 10900.528428662346, 11043.0, 11103.72240693989, 11126.554937802397, 11170.050545703707, 11208.0, 11402.315861048364, 11409.543618255177], "value": 7152.9456714342905, "unit": "score"}], "name": "dromaeo_css", "value": 7015.617882273136, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 112.0, 112.0, 114.0, 115.0, 97.0, 153.0, 167.0], "value": 119.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 113.0, 113.0, 149.0, 111.0, 111.0, 112.0, 124.0, 139.0, 111.0], "value": 122.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 184.0, 188.0, 181.0, 195.0, 261.0, 181.0, 180.0, 184.0], "value": 192.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 58.0, 90.0, 62.0, 63.0, 58.0, 59.0, 83.0, 59.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 93.0, 69.0, 68.0, 70.0, 89.0, 67.0, 68.0, 92.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 91.0, 128.0, 121.0, 85.0, 88.0, 86.0, 87.0, 85.0], "value": 94.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 94.0, 95.0, 92.0, 93.0, 94.0, 97.0, 94.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 81.0, 80.0, 82.0, 84.0, 79.0, 86.0, 81.0, 80.0], "value": 82.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 54.0, 49.0, 55.0, 54.0, 54.0, 55.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 69.0, 77.0, 74.0, 72.0, 72.0, 72.0, 72.0, 72.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 87.0, 88.0, 84.0, 86.0, 77.0, 98.0, 97.0, 108.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 165.0, 162.0, 154.0, 163.0, 159.0, 162.0, 196.0, 163.0], "value": 162.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 171.0, 173.0, 193.0, 244.0, 227.0, 170.0, 176.0, 172.0, 176.0], "value": 187.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 64.0, 87.0, 67.0, 68.0, 67.0, 66.0, 68.0], "value": 69.2, "unit": "score"}], "name": "kraken", "value": 1484.5, "alertThreshold": 2.0}]} 06:01:22 INFO - Return code: 0 06:01:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:01:22 INFO - # TBPL SUCCESS # 06:01:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:01:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:01:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:01:22 INFO - 2016-11-13 06:01:22.737 screenresolution[1961:66955] starting screenresolution argv=screenresolution get 06:01:22 INFO - 2016-11-13 06:01:22.740 screenresolution[1961:66955] Display 0: 1600x1200x32@60 06:01:22 INFO - 2016-11-13 06:01:22.746 screenresolution[1962:66958] starting screenresolution argv=screenresolution list 06:01:22 INFO - Available Modes on Display 0 06:01:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:01:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:01:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:01:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:01:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:01:22 INFO - Intel Iris: 06:01:22 INFO - Chipset Model: Intel Iris 06:01:22 INFO - Type: GPU 06:01:22 INFO - Bus: Built-In 06:01:22 INFO - VRAM (Dynamic, Max): 1536 MB 06:01:22 INFO - Vendor: Intel (0x8086) 06:01:22 INFO - Device ID: 0x0a2e 06:01:22 INFO - Revision ID: 0x0009 06:01:22 INFO - Displays: 06:01:22 INFO - Display: 06:01:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:01:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:01:22 INFO - Display Serial Number: 1600x1200 60 06:01:22 INFO - Main Display: Yes 06:01:22 INFO - Mirror: Off 06:01:22 INFO - Online: Yes 06:01:22 INFO - Rotation: Supported 06:01:22 INFO - Return code: 0 06:01:22 INFO - Running post-action listener: _resource_record_post_action 06:01:22 INFO - [mozharness: 2016-11-13 14:01:22.982590Z] Finished run-tests step (success) 06:01:22 INFO - Running post-run listener: _resource_record_post_run 06:01:23 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 57344; Write bytes: 698580480; Read time: 1; Write time: 1965 06:01:23 INFO - TinderboxPrint: CPU usage
53.7% 06:01:23 INFO - TinderboxPrint: I/O read bytes / time
57,344 / 1 06:01:23 INFO - TinderboxPrint: I/O write bytes / time
698,580,480 / 1,965 06:01:23 INFO - TinderboxPrint: CPU idle
2,249.4 (46.3%) 06:01:23 INFO - TinderboxPrint: CPU system
975.9 (20.1%) 06:01:23 INFO - TinderboxPrint: CPU user
1,636.4 (33.7%) 06:01:23 INFO - TinderboxPrint: Swap in / out
398,213,120 / 0 06:01:23 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 387805696; Write bytes: 383244288; Read time: 29383; Write time: 782 06:01:23 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 57344; Write bytes: 295130624; Read time: 1; Write time: 1131 06:01:23 INFO - Running post-run listener: _upload_blobber_files 06:01:23 INFO - Blob upload gear active. 06:01:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:01:23 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 06:01:23 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:01:23 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:01:23 INFO - (blobuploader) - INFO - Open directory for files ... 06:01:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:01:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:01:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:24 INFO - (blobuploader) - INFO - Done attempting. 06:01:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:01:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:01:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:25 INFO - (blobuploader) - INFO - Done attempting. 06:01:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:01:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:01:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:27 INFO - (blobuploader) - INFO - Done attempting. 06:01:27 INFO - (blobuploader) - INFO - Iteration through files over. 06:01:27 INFO - Return code: 0 06:01:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:01:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:01:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f45e10d445da5e6c5d4c9a933f263045f492179aa646698583694e898a51f19601f1f871110366cf69279a30e65411c5c14c3a0f4b5276feb271dfd6d9212a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d308507de9f7625ffd91e976cc419c9e695c597b3d256a7e49a73c871fc29e34e81306d96d36ad4517b969b06e618c8a2172c1de171a68779c9d26d1b30481e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4213bc45e3b3a9f7c1991d02b9c557e4b6588580ec76c5a19a338efcc3b74a14af9f30feff15ab442c4497cdf78fc883194d9c5ff99faaf3e1325f9fbf51945b"} 06:01:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:01:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:01:27 INFO - Contents: 06:01:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f45e10d445da5e6c5d4c9a933f263045f492179aa646698583694e898a51f19601f1f871110366cf69279a30e65411c5c14c3a0f4b5276feb271dfd6d9212a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d308507de9f7625ffd91e976cc419c9e695c597b3d256a7e49a73c871fc29e34e81306d96d36ad4517b969b06e618c8a2172c1de171a68779c9d26d1b30481e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4213bc45e3b3a9f7c1991d02b9c557e4b6588580ec76c5a19a338efcc3b74a14af9f30feff15ab442c4497cdf78fc883194d9c5ff99faaf3e1325f9fbf51945b"} 06:01:27 INFO - Running post-run listener: copy_logs_to_upload_dir 06:01:27 INFO - Copying logs to upload dir... 06:01:27 INFO - mkdir: /builds/slave/test/build/upload/logs 06:01:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.156998 ========= master_lag: 0.78 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-13 06:01:28.750551) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-13 06:01:28.754166) ========= 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.XLpMTWadOD/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.OGojxSPos2/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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/Firefox/sha512/9f45e10d445da5e6c5d4c9a933f263045f492179aa646698583694e898a51f19601f1f871110366cf69279a30e65411c5c14c3a0f4b5276feb271dfd6d9212a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d308507de9f7625ffd91e976cc419c9e695c597b3d256a7e49a73c871fc29e34e81306d96d36ad4517b969b06e618c8a2172c1de171a68779c9d26d1b30481e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4213bc45e3b3a9f7c1991d02b9c557e4b6588580ec76c5a19a338efcc3b74a14af9f30feff15ab442c4497cdf78fc883194d9c5ff99faaf3e1325f9fbf51945b"} build_url:https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010960 build_url: 'https://queue.taskcluster.net/v1/task/NiMkvmq5RyCPD5pgl2QN9g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f45e10d445da5e6c5d4c9a933f263045f492179aa646698583694e898a51f19601f1f871110366cf69279a30e65411c5c14c3a0f4b5276feb271dfd6d9212a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d308507de9f7625ffd91e976cc419c9e695c597b3d256a7e49a73c871fc29e34e81306d96d36ad4517b969b06e618c8a2172c1de171a68779c9d26d1b30481e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4213bc45e3b3a9f7c1991d02b9c557e4b6588580ec76c5a19a338efcc3b74a14af9f30feff15ab442c4497cdf78fc883194d9c5ff99faaf3e1325f9fbf51945b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-13 06:01:28.788043) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 06:01:28.788363) ========= 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.XLpMTWadOD/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.OGojxSPos2/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.005195 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 06:01:28.837596) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-13 06:01:28.837935) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-13 06:01:28.838274) ========= ========= Total master_lag: 0.92 =========