builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0104 starttime: 1481930636.64 results: success (0) buildid: 20161216133950 builduid: 6bd54fdeac9d46f9b5f3d439a97cdcc6 revision: 3ddf24a98ed75a69527322b771c870fe2694f5c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 15:23:56.638089) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 15:23:56.638578) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 15:23:56.638893) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 15:23:56.665460) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 15:23:56.665735) ========= 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.wgzk4B55Cz/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.yYWre2WoGg/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-16 15:23:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.0M=0.001s 2016-12-16 15:23:56 (19.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.099741 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 15:23:56.789509) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 15:23:56.789822) ========= 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.wgzk4B55Cz/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.yYWre2WoGg/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.026088 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 15:23:56.838160) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-16 15:23:56.838553) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3ddf24a98ed75a69527322b771c870fe2694f5c1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3ddf24a98ed75a69527322b771c870fe2694f5c1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wgzk4B55Cz/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.yYWre2WoGg/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-16 15:23:56,930 truncating revision to first 12 chars 2016-12-16 15:23:56,931 Setting DEBUG logging. 2016-12-16 15:23:56,931 attempt 1/10 2016-12-16 15:23:56,931 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3ddf24a98ed7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-16 15:23:57,917 unpacking tar archive at: mozilla-aurora-3ddf24a98ed7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.275160 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-16 15:23:58.137362) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 15:23:58.137697) ========= script_repo_revision: 3ddf24a98ed75a69527322b771c870fe2694f5c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 15:23:58.138074) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 15:23:58.138395) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 15:23:58.158366) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-16 15:23:58.158731) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wgzk4B55Cz/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.yYWre2WoGg/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:23:58 INFO - MultiFileLogger online at 20161216 15:23:58 in /builds/slave/test 15:23:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:23:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:23:58 INFO - {'append_to_log': False, 15:23:58 INFO - 'base_work_dir': '/builds/slave/test', 15:23:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:23:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:23:58 INFO - 'branch': 'Mozilla-Aurora', 15:23:58 INFO - 'buildbot_json_path': 'buildprops.json', 15:23:58 INFO - 'config_files': ('talos/mac_config.py',), 15:23:58 INFO - 'default_actions': ('clobber', 15:23:58 INFO - 'read-buildbot-config', 15:23:58 INFO - 'download-and-extract', 15:23:58 INFO - 'populate-webroot', 15:23:58 INFO - 'create-virtualenv', 15:23:58 INFO - 'install', 15:23:58 INFO - 'run-tests'), 15:23:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:23:58 INFO - 'download_minidump_stackwalk': True, 15:23:58 INFO - 'download_symbols': 'ondemand', 15:23:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:23:58 INFO - 'tooltool.py': '/tools/tooltool.py', 15:23:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:23:58 INFO - '/tools/misc-python/virtualenv.py')}, 15:23:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:23:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:23:58 INFO - 'installer_path': 'installer.exe', 15:23:58 INFO - 'log_level': 'info', 15:23:58 INFO - 'log_name': 'talos', 15:23:58 INFO - 'log_to_console': True, 15:23:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:23:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:23:58 INFO - 'opt_config_files': (), 15:23:58 INFO - 'pip_index': False, 15:23:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:23:58 INFO - 'cmd': ('bash', 15:23:58 INFO - '-c', 15:23:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:23:58 INFO - 'enabled': True, 15:23:58 INFO - 'halt_on_failure': False, 15:23:58 INFO - 'name': 'check_screen_resolution'},), 15:23:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:23:58 INFO - 'cmd': ('bash', 15:23:58 INFO - '-c', 15:23:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:23:58 INFO - 'enabled': True, 15:23:58 INFO - 'halt_on_failure': False, 15:23:58 INFO - 'name': 'check_screen_resolution'},), 15:23:58 INFO - 'run_cmd_checks_enabled': True, 15:23:58 INFO - 'sps_profile': False, 15:23:58 INFO - 'sps_profile_interval': 0, 15:23:58 INFO - 'suite': 'dromaeojs', 15:23:58 INFO - 'system_bits': '32', 15:23:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:23:58 INFO - 'title': 't-yosemite-r7-0104', 15:23:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:23:58 INFO - 'use_talos_json': True, 15:23:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:23:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:23:58 INFO - 'work_dir': 'build'} 15:23:58 INFO - [mozharness: 2016-12-16 23:23:58.359656Z] Running clobber step. 15:23:58 INFO - Running pre-action listener: _resource_record_pre_action 15:23:58 INFO - Running main action method: clobber 15:23:58 INFO - rmtree: /builds/slave/test/build 15:23:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:24:00 INFO - Running post-action listener: _resource_record_post_action 15:24:00 INFO - [mozharness: 2016-12-16 23:24:00.926642Z] Finished clobber step (success) 15:24:00 INFO - [mozharness: 2016-12-16 23:24:00.926921Z] Running read-buildbot-config step. 15:24:00 INFO - Running pre-action listener: _resource_record_pre_action 15:24:00 INFO - Running main action method: read_buildbot_config 15:24:00 INFO - Using buildbot properties: 15:24:00 INFO - { 15:24:00 INFO - "project": "", 15:24:00 INFO - "product": "firefox", 15:24:00 INFO - "script_repo_revision": "production", 15:24:00 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:24:00 INFO - "repository": "", 15:24:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:24:00 INFO - "buildid": "20161216133950", 15:24:00 INFO - "pgo_build": "False", 15:24:00 INFO - "basedir": "/builds/slave/test", 15:24:00 INFO - "buildnumber": 67, 15:24:00 INFO - "slavename": "t-yosemite-r7-0104", 15:24:00 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 15:24:00 INFO - "platform": "yosemite_r7", 15:24:00 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:24:00 INFO - "branch": "mozilla-aurora", 15:24:00 INFO - "repo_path": "releases/mozilla-aurora", 15:24:00 INFO - "revision": "3ddf24a98ed75a69527322b771c870fe2694f5c1", 15:24:00 INFO - "stage_platform": "macosx64", 15:24:00 INFO - "builduid": "6bd54fdeac9d46f9b5f3d439a97cdcc6", 15:24:00 INFO - "slavebuilddir": "test" 15:24:00 INFO - } 15:24:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 15:24:00 INFO - Running post-action listener: _resource_record_post_action 15:24:00 INFO - [mozharness: 2016-12-16 23:24:00.932315Z] Finished read-buildbot-config step (success) 15:24:00 INFO - [mozharness: 2016-12-16 23:24:00.932528Z] Running download-and-extract step. 15:24:00 INFO - Running pre-action listener: _resource_record_pre_action 15:24:00 INFO - Running main action method: download_and_extract 15:24:00 INFO - mkdir: /builds/slave/test/build/tests 15:24: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')]} 15:24:00 INFO - trying https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 15:24:00 INFO - Downloading https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 15:24:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 15:24:02 INFO - Downloaded 1199 bytes. 15:24:02 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 15:24:02 INFO - Using the following test package requirements: 15:24:02 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:24:02 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:24:02 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 15:24:02 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:24:02 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 15:24:02 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:24:02 INFO - u'jsshell-mac.zip'], 15:24:02 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:24:02 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 15:24:02 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:24:02 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:24:02 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 15:24:02 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:24:02 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 15:24:02 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:24:02 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 15:24:02 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:24:02 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 15:24:02 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:24:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:24:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:24:02 INFO - Fetch https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:24:02 INFO - Http code: 200 15:24:02 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 15:24:02 INFO - x-amz-cf-id: sJysxZ1n3h6IPFXatFxJx9kzvdWe_I_dhuRvc1F3l2f-i4IpLXqxUg== 15:24:02 INFO - x-amz-version-id: OfLNfaQutO7LMP92b56Q7BXRp9nd35gg 15:24:03 INFO - Expected file size: 16280311 15:24:03 INFO - Obtained file size: 16280311 15:24:09 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:24:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:24:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:24:09 INFO - Fetch https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:24:10 INFO - Http code: 200 15:24:10 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 15:24:10 INFO - x-amz-cf-id: 9CpAFq-gOh7-qi6T73wqMTEdx75gqjj4e8oNF_WWcKo2y8cN-1saZA== 15:24:10 INFO - x-amz-version-id: OfLNfaQutO7LMP92b56Q7BXRp9nd35gg 15:24:10 INFO - Expected file size: 16280311 15:24:10 INFO - Obtained file size: 16280311 15:24:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 15:24:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:24:17 INFO - Fetch https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 15:24:17 INFO - Http code: 200 15:24:17 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 15:24:17 INFO - x-amz-cf-id: 0wvyrz_oAvou3STn8vzuoph20Zal4ktrRcwleIFlwADHoT-RYFrQ9Q== 15:24:17 INFO - x-amz-version-id: ojGYjety5FICwGOVASaQSOOwZ_4KgFOy 15:24:17 INFO - Expected file size: 14409038 15:24:17 INFO - Obtained file size: 14409038 15:24:18 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')]} 15:24:18 INFO - trying https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:24:18 INFO - Downloading https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 15:24:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 15:24:21 INFO - Downloaded 109662932 bytes. 15:24:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:24:21 INFO - mkdir: /builds/slave/test/properties 15:24:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:24:21 INFO - Writing to file /builds/slave/test/properties/build_url 15:24:21 INFO - Contents: 15:24:21 INFO - build_url:https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:24:22 INFO - Running post-action listener: _resource_record_post_action 15:24:22 INFO - Running post-action listener: set_extra_try_arguments 15:24:22 INFO - [mozharness: 2016-12-16 23:24:22.167990Z] Finished download-and-extract step (success) 15:24:22 INFO - [mozharness: 2016-12-16 23:24:22.168127Z] Running populate-webroot step. 15:24:22 INFO - Running pre-action listener: _resource_record_pre_action 15:24:22 INFO - Running main action method: populate_webroot 15:24:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:24:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:24:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:24:22 INFO - '--tppagecycles', 15:24:22 INFO - '7'], 15:24:22 INFO - 'tests': ['tsvgm']}}, 15:24:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:24:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:24:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:24:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:24:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:24:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:24:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:22 INFO - 'talos_options': ['--disable-e10s'], 15:24:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:24:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:24:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:22 INFO - 'talos_options': ['--disable-e10s'], 15:24:22 INFO - 'tests': ['damp', 'tps']}, 15:24:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:22 INFO - 'tests': ['damp', 'tps']}, 15:24:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:24:22 INFO - 'tests': ['dromaeo_dom']}, 15:24:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:24:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:24:22 INFO - 'tests': ['basic_compositor_video']}, 15:24:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:24:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:24:22 INFO - 'tests': ['a11yr', 15:24:22 INFO - 'ts_paint', 15:24:22 INFO - 'tpaint', 15:24:22 INFO - 'sessionrestore', 15:24:22 INFO - 'sessionrestore_no_auto_restore', 15:24:22 INFO - 'tabpaint']}, 15:24:22 INFO - 'other-e10s': {'tests': ['a11yr', 15:24:22 INFO - 'ts_paint', 15:24:22 INFO - 'tpaint', 15:24:22 INFO - 'sessionrestore', 15:24:22 INFO - 'sessionrestore_no_auto_restore', 15:24:22 INFO - 'tabpaint']}, 15:24:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:24:22 INFO - 'ts_paint', 15:24:22 INFO - 'tpaint', 15:24:22 INFO - 'sessionrestore', 15:24:22 INFO - 'sessionrestore_no_auto_restore', 15:24:22 INFO - 'tabpaint']}, 15:24:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:24:22 INFO - 'ts_paint', 15:24:22 INFO - 'tpaint', 15:24:22 INFO - 'sessionrestore', 15:24:22 INFO - 'sessionrestore_no_auto_restore', 15:24:22 INFO - 'tabpaint']}, 15:24:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:24:22 INFO - 'tests': ['a11yr', 15:24:22 INFO - 'ts_paint', 15:24:22 INFO - 'tpaint', 15:24:22 INFO - 'sessionrestore', 15:24:22 INFO - 'sessionrestore_no_auto_restore', 15:24:22 INFO - 'tabpaint']}, 15:24:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:24:22 INFO - 'tests': ['a11yr', 15:24:22 INFO - 'ts_paint', 15:24:22 INFO - 'tpaint', 15:24:22 INFO - 'sessionrestore', 15:24:22 INFO - 'sessionrestore_no_auto_restore', 15:24:22 INFO - 'tabpaint']}, 15:24:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:24:22 INFO - 'tests': ['tsvgx', 15:24:22 INFO - 'tsvgr_opacity', 15:24:22 INFO - 'tart', 15:24:22 INFO - 'tscrollx', 15:24:22 INFO - 'cart']}, 15:24:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:24:22 INFO - 'tsvgr_opacity', 15:24:22 INFO - 'tart', 15:24:22 INFO - 'tscrollx', 15:24:22 INFO - 'cart']}, 15:24:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:22 INFO - 'talos_options': ['--disable-e10s'], 15:24:22 INFO - 'tests': ['tp5o']}, 15:24:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:22 INFO - 'tests': ['tp5o']}, 15:24:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:22 INFO - 'talos_options': ['--disable-e10s', 15:24:22 INFO - '--xperf_path', 15:24:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:24:22 INFO - 'tests': ['tp5n']}, 15:24:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:24:22 INFO - 'talos_options': ['--xperf_path', 15:24:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:24:22 INFO - 'tests': ['tp5n']}}, 15:24:22 INFO - 'talos.zip': {'path': '', 15:24:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:24:22 INFO - Running post-action listener: _resource_record_post_action 15:24:22 INFO - [mozharness: 2016-12-16 23:24:22.186807Z] Finished populate-webroot step (success) 15:24:22 INFO - [mozharness: 2016-12-16 23:24:22.186939Z] Running create-virtualenv step. 15:24:22 INFO - Running pre-action listener: _resource_record_pre_action 15:24:22 INFO - Running main action method: create_virtualenv 15:24:22 INFO - Creating virtualenv /builds/slave/test/build/venv 15:24:22 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 15:24:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:24:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:24:22 INFO - Using real prefix '/tools/python27' 15:24:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:24:23 INFO - Installing distribute.............................................................................................................................................................................................done. 15:24:26 INFO - Installing pip.................done. 15:24:26 INFO - Return code: 0 15:24:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:24:26 INFO - Reading from file tmpfile_stdout 15:24:26 INFO - Output received: 15:24:26 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) 15:24:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101be7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9592db6f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fbd30>, '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 0x101861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f3b20>, '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.wgzk4B55Cz/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.yYWre2WoGg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:24:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:24:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wgzk4B55Cz/Render', 15:24:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:26 INFO - 'HOME': '/Users/cltbld', 15:24:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:26 INFO - 'LOGNAME': 'cltbld', 15:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:26 INFO - 'MOZ_NO_REMOTE': '1', 15:24:26 INFO - 'NO_EM_RESTART': '1', 15:24:26 INFO - 'PAGER': '/bin/cat', 15:24:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:26 INFO - 'PWD': '/builds/slave/test', 15:24:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:26 INFO - 'SHELL': '/bin/bash', 15:24:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYWre2WoGg/Listeners', 15:24:26 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:24:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:26 INFO - 'USER': 'cltbld', 15:24:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:26 INFO - 'XPC_FLAGS': '0x0', 15:24:26 INFO - 'XPC_SERVICE_NAME': '0', 15:24:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:26 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 15:24:26 INFO - Cleaning up... 15:24:26 INFO - Return code: 0 15:24:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101be7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9592db6f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fbd30>, '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 0x101861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f3b20>, '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.wgzk4B55Cz/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.yYWre2WoGg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:24:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:24:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wgzk4B55Cz/Render', 15:24:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:26 INFO - 'HOME': '/Users/cltbld', 15:24:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:26 INFO - 'LOGNAME': 'cltbld', 15:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:26 INFO - 'MOZ_NO_REMOTE': '1', 15:24:26 INFO - 'NO_EM_RESTART': '1', 15:24:26 INFO - 'PAGER': '/bin/cat', 15:24:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:26 INFO - 'PWD': '/builds/slave/test', 15:24:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:26 INFO - 'SHELL': '/bin/bash', 15:24:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYWre2WoGg/Listeners', 15:24:26 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:24:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:26 INFO - 'USER': 'cltbld', 15:24:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:26 INFO - 'XPC_FLAGS': '0x0', 15:24:26 INFO - 'XPC_SERVICE_NAME': '0', 15:24:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:26 INFO - Downloading/unpacking psutil>=3.1.1 15:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:24:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:24:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:24:30 INFO - Installing collected packages: psutil 15:24:30 INFO - Running setup.py install for psutil 15:24:30 INFO - building 'psutil._psutil_osx' extension 15:24:30 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 15:24:30 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 15:24:30 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 15:24:31 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 15:24:31 INFO - building 'psutil._psutil_posix' extension 15:24:31 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 15:24:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:24:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:24:31 INFO - ^ 15:24:31 INFO - 1 warning generated. 15:24:31 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 15:24:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:24:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:24:31 INFO - Successfully installed psutil 15:24:31 INFO - Cleaning up... 15:24:31 INFO - Return code: 0 15:24:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:24: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')]} 15:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24: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')]} 15:24:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101be7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9592db6f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fbd30>, '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 0x101861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f3b20>, '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.wgzk4B55Cz/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.yYWre2WoGg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:24:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:24:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wgzk4B55Cz/Render', 15:24:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:31 INFO - 'HOME': '/Users/cltbld', 15:24:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:31 INFO - 'LOGNAME': 'cltbld', 15:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:31 INFO - 'MOZ_NO_REMOTE': '1', 15:24:31 INFO - 'NO_EM_RESTART': '1', 15:24:31 INFO - 'PAGER': '/bin/cat', 15:24:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:31 INFO - 'PWD': '/builds/slave/test', 15:24:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:31 INFO - 'SHELL': '/bin/bash', 15:24:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYWre2WoGg/Listeners', 15:24:31 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:24:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:31 INFO - 'USER': 'cltbld', 15:24:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:31 INFO - 'XPC_FLAGS': '0x0', 15:24:31 INFO - 'XPC_SERVICE_NAME': '0', 15:24:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:24:31 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 15:24:31 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 15:24:31 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 15:24:31 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 15:24:31 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 15:24:31 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 15:24:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:24:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:24:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:24:34 INFO - Installing collected packages: mozsystemmonitor 15:24:34 INFO - Running setup.py install for mozsystemmonitor 15:24:34 INFO - Successfully installed mozsystemmonitor 15:24:34 INFO - Cleaning up... 15:24:34 INFO - Return code: 0 15:24:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:24:34 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')]} 15:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:34 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')]} 15:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:34 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 0x101be7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9592db6f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fbd30>, '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 0x101861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f3b20>, '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.wgzk4B55Cz/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.yYWre2WoGg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:24:34 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 15:24:34 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 15:24:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wgzk4B55Cz/Render', 15:24:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:34 INFO - 'HOME': '/Users/cltbld', 15:24:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:34 INFO - 'LOGNAME': 'cltbld', 15:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:34 INFO - 'MOZ_NO_REMOTE': '1', 15:24:34 INFO - 'NO_EM_RESTART': '1', 15:24:34 INFO - 'PAGER': '/bin/cat', 15:24:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:34 INFO - 'PWD': '/builds/slave/test', 15:24:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:34 INFO - 'SHELL': '/bin/bash', 15:24:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYWre2WoGg/Listeners', 15:24:34 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:24:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:34 INFO - 'USER': 'cltbld', 15:24:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:34 INFO - 'XPC_FLAGS': '0x0', 15:24:34 INFO - 'XPC_SERVICE_NAME': '0', 15:24:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:34 INFO - Downloading/unpacking jsonschema==2.5.1 15:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:24:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:37 INFO - Downloading functools32-3.2.3-2.tar.gz 15:24:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:24:37 INFO - warning: no files found matching '*.txt' 15:24:37 INFO - no previously-included directories found matching 'build' 15:24:37 INFO - no previously-included directories found matching 'dist' 15:24:37 INFO - no previously-included directories found matching '.git*' 15:24:37 INFO - Installing collected packages: jsonschema, functools32 15:24:37 INFO - Running setup.py install for functools32 15:24:37 INFO - warning: no files found matching '*.txt' 15:24:37 INFO - no previously-included directories found matching 'build' 15:24:37 INFO - no previously-included directories found matching 'dist' 15:24:37 INFO - no previously-included directories found matching '.git*' 15:24:37 INFO - Successfully installed jsonschema functools32 15:24:37 INFO - Cleaning up... 15:24:38 INFO - Return code: 0 15:24:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:24:38 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')]} 15:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:38 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')]} 15:24:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:38 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 0x101be7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9592db6f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fbd30>, '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 0x101861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f3b20>, '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.wgzk4B55Cz/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.yYWre2WoGg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:24:38 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 15:24:38 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 15:24:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wgzk4B55Cz/Render', 15:24:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:38 INFO - 'HOME': '/Users/cltbld', 15:24:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:38 INFO - 'LOGNAME': 'cltbld', 15:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:38 INFO - 'MOZ_NO_REMOTE': '1', 15:24:38 INFO - 'NO_EM_RESTART': '1', 15:24:38 INFO - 'PAGER': '/bin/cat', 15:24:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:38 INFO - 'PWD': '/builds/slave/test', 15:24:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:38 INFO - 'SHELL': '/bin/bash', 15:24:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYWre2WoGg/Listeners', 15:24:38 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:24:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:38 INFO - 'USER': 'cltbld', 15:24:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:38 INFO - 'XPC_FLAGS': '0x0', 15:24:38 INFO - 'XPC_SERVICE_NAME': '0', 15:24:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:24:38 INFO - Cleaning up... 15:24:38 INFO - Return code: 0 15:24:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:24:38 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')]} 15:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:38 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')]} 15:24:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:38 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 0x101be7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9592db6f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fbd30>, '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 0x101861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f3b20>, '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.wgzk4B55Cz/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.yYWre2WoGg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:24:38 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 15:24:38 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 15:24:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wgzk4B55Cz/Render', 15:24:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:38 INFO - 'HOME': '/Users/cltbld', 15:24:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:38 INFO - 'LOGNAME': 'cltbld', 15:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:38 INFO - 'MOZ_NO_REMOTE': '1', 15:24:38 INFO - 'NO_EM_RESTART': '1', 15:24:38 INFO - 'PAGER': '/bin/cat', 15:24:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:38 INFO - 'PWD': '/builds/slave/test', 15:24:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:38 INFO - 'SHELL': '/bin/bash', 15:24:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYWre2WoGg/Listeners', 15:24:38 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:24:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:38 INFO - 'USER': 'cltbld', 15:24:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:38 INFO - 'XPC_FLAGS': '0x0', 15:24:38 INFO - 'XPC_SERVICE_NAME': '0', 15:24:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:38 INFO - Downloading/unpacking blobuploader==1.2.4 15:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:41 INFO - Downloading blobuploader-1.2.4.tar.gz 15:24:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:24:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:24:41 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 15:24:41 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 15:24:41 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 15:24:41 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 15:24:41 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 15:24:41 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 15:24:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:24:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:24:41 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 15:24:41 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 15:24:41 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 15:24:41 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 15:24:41 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 15:24:41 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 15:24:42 INFO - Downloading docopt-0.6.1.tar.gz 15:24:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:24:42 INFO - Installing collected packages: blobuploader, requests, docopt 15:24:42 INFO - Running setup.py install for blobuploader 15:24:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:24:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:24:42 INFO - Running setup.py install for requests 15:24:43 INFO - Running setup.py install for docopt 15:24:43 INFO - Successfully installed blobuploader requests docopt 15:24:43 INFO - Cleaning up... 15:24:43 INFO - Return code: 0 15:24:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:24:43 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')]} 15:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:43 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')]} 15:24:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:43 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 0x101be7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9592db6f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fbd30>, '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 0x101861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f3b20>, '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.wgzk4B55Cz/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.yYWre2WoGg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:24:43 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 15:24:43 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 15:24:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wgzk4B55Cz/Render', 15:24:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:43 INFO - 'HOME': '/Users/cltbld', 15:24:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:43 INFO - 'LOGNAME': 'cltbld', 15:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:43 INFO - 'MOZ_NO_REMOTE': '1', 15:24:43 INFO - 'NO_EM_RESTART': '1', 15:24:43 INFO - 'PAGER': '/bin/cat', 15:24:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:43 INFO - 'PWD': '/builds/slave/test', 15:24:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:43 INFO - 'SHELL': '/bin/bash', 15:24:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYWre2WoGg/Listeners', 15:24:43 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:24:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:43 INFO - 'USER': 'cltbld', 15:24:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:43 INFO - 'XPC_FLAGS': '0x0', 15:24:43 INFO - 'XPC_SERVICE_NAME': '0', 15:24:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:24:43 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-fXkD_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:24:43 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-G8SnlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:24:43 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-1oKrST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:24:43 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-JjwdaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:24:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-sPDysf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:24:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-1MB517-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:24:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-PdoaK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:24:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-hQHzsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:24:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-MM62dp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:24:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-iPKphG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:24:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-8B__h0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:24:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-OFNUF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:24:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-b4Q881-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:24:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-JSkBrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:45 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-JQPxQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:24:45 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-2hPtFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:24:45 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-GOP1ky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:24:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:24:45 INFO - Running setup.py install for manifestparser 15:24:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:24:45 INFO - Running setup.py install for mozcrash 15:24:45 INFO - Running setup.py install for mozdebug 15:24:45 INFO - Running setup.py install for mozdevice 15:24:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:24:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:24:46 INFO - Running setup.py install for mozfile 15:24:46 INFO - Running setup.py install for mozhttpd 15:24:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:24:46 INFO - Running setup.py install for mozinfo 15:24:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:24:46 INFO - Running setup.py install for mozInstall 15:24:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:24:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:24:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:24:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:24:46 INFO - Running setup.py install for mozleak 15:24:46 INFO - Running setup.py install for mozlog 15:24:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:24:46 INFO - Running setup.py install for moznetwork 15:24:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:24:47 INFO - Running setup.py install for mozprocess 15:24:47 INFO - Running setup.py install for mozprofile 15:24:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:24:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:24:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:24:47 INFO - Running setup.py install for mozrunner 15:24:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:24:47 INFO - Running setup.py install for mozscreenshot 15:24:47 INFO - Running setup.py install for moztest 15:24:47 INFO - Running setup.py install for mozversion 15:24:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:24:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:24:47 INFO - Cleaning up... 15:24:47 INFO - Return code: 0 15:24:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:24: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')]} 15:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24: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')]} 15:24:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:47 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 0x101be7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9592db6f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fbd30>, '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 0x101861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f3b20>, '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.wgzk4B55Cz/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.yYWre2WoGg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:24:47 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 15:24:47 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 15:24:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wgzk4B55Cz/Render', 15:24:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:47 INFO - 'HOME': '/Users/cltbld', 15:24:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:47 INFO - 'LOGNAME': 'cltbld', 15:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:47 INFO - 'MOZ_NO_REMOTE': '1', 15:24:47 INFO - 'NO_EM_RESTART': '1', 15:24:47 INFO - 'PAGER': '/bin/cat', 15:24:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:47 INFO - 'PWD': '/builds/slave/test', 15:24:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:47 INFO - 'SHELL': '/bin/bash', 15:24:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYWre2WoGg/Listeners', 15:24:47 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:24:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:47 INFO - 'USER': 'cltbld', 15:24:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:47 INFO - 'XPC_FLAGS': '0x0', 15:24:47 INFO - 'XPC_SERVICE_NAME': '0', 15:24:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:24:48 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-SCw3x5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:24:48 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)) 15:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:24:48 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-B3h5hI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:24:48 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)) 15:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:24:48 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-EoCgzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:24:48 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)) 15:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:24:48 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-A7fyCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:24:48 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)) 15:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:24:48 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-yN8utb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:24:48 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)) 15:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:24:48 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-RkzJe6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:24:48 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)) 15:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:24:48 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-wU8cNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:24:48 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)) 15:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:24:48 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Qm3Zkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:24:49 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)) 15:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:24:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-2x3rjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:24:49 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)) 15:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:24:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-YXhh4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:24:49 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)) 15:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:24:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-aKQyDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:24:49 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)) 15:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:24:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-3vT51c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:24:49 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)) 15:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:24:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-3AfRBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:24:49 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)) 15:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:24:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-3YozM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:24:49 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)) 15:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-LmX9SV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:49 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)) 15:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:24:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-vXuCtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:24:49 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)) 15:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:24:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-d4k7Xt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:24:50 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)) 15:24:50 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)) 15:24:50 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)) 15:24:50 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)) 15:24:50 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)) 15:24:50 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)) 15:24:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:24: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 15:24: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 15:24: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 15:24: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 15:24: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 15:24: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 15:24:52 INFO - Downloading blessings-1.6.tar.gz 15:24:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:24:52 INFO - Installing collected packages: blessings 15:24:52 INFO - Running setup.py install for blessings 15:24:52 INFO - Successfully installed blessings 15:24:52 INFO - Cleaning up... 15:24:53 INFO - Return code: 0 15:24:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:24:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:24:53 INFO - Reading from file tmpfile_stdout 15:24:53 INFO - Current package versions: 15:24:53 INFO - blessings == 1.6 15:24:53 INFO - blobuploader == 1.2.4 15:24:53 INFO - docopt == 0.6.1 15:24:53 INFO - functools32 == 3.2.3-2 15:24:53 INFO - jsonschema == 2.5.1 15:24:53 INFO - manifestparser == 1.1 15:24:53 INFO - mozInstall == 1.12 15:24:53 INFO - mozcrash == 1.0 15:24:53 INFO - mozdebug == 0.1 15:24:53 INFO - mozdevice == 0.48 15:24:53 INFO - mozfile == 1.2 15:24:53 INFO - mozhttpd == 0.7 15:24:53 INFO - mozinfo == 0.9 15:24:53 INFO - mozleak == 0.1 15:24:53 INFO - mozlog == 3.4 15:24:53 INFO - moznetwork == 0.27 15:24:53 INFO - mozprocess == 0.23 15:24:53 INFO - mozprofile == 0.28 15:24:53 INFO - mozrunner == 6.13 15:24:53 INFO - mozscreenshot == 0.1 15:24:53 INFO - mozsystemmonitor == 0.3 15:24:53 INFO - moztest == 0.7 15:24:53 INFO - mozversion == 1.4 15:24:53 INFO - psutil == 3.1.1 15:24:53 INFO - requests == 1.2.3 15:24:53 INFO - wsgiref == 0.1.2 15:24:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:24:53 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')]} 15:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:53 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')]} 15:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:54 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 0x101be7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9592db6f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fbd30>, '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 0x101861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f3b20>, '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.wgzk4B55Cz/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.yYWre2WoGg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:24:54 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 15:24:54 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 15:24:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wgzk4B55Cz/Render', 15:24:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:54 INFO - 'HOME': '/Users/cltbld', 15:24:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:54 INFO - 'LOGNAME': 'cltbld', 15:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:54 INFO - 'MOZ_NO_REMOTE': '1', 15:24:54 INFO - 'NO_EM_RESTART': '1', 15:24:54 INFO - 'PAGER': '/bin/cat', 15:24:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:54 INFO - 'PWD': '/builds/slave/test', 15:24:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:54 INFO - 'SHELL': '/bin/bash', 15:24:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYWre2WoGg/Listeners', 15:24:54 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:24:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:54 INFO - 'USER': 'cltbld', 15:24:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:54 INFO - 'XPC_FLAGS': '0x0', 15:24:54 INFO - 'XPC_SERVICE_NAME': '0', 15:24:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:54 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)) 15:24:54 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)) 15:24:54 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)) 15:24:54 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)) 15:24:54 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)) 15:24:54 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)) 15:24:54 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)) 15:24:54 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)) 15:24:54 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)) 15:24:54 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)) 15:24:54 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)) 15:24:54 INFO - Cleaning up... 15:24:54 INFO - Return code: 0 15:24:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:24:54 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')]} 15:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:54 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')]} 15:24:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:54 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 0x101be7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9592db6f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fbd30>, '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 0x101861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f3b20>, '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.wgzk4B55Cz/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.yYWre2WoGg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:24:54 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 15:24:54 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 15:24:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wgzk4B55Cz/Render', 15:24:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:54 INFO - 'HOME': '/Users/cltbld', 15:24:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:54 INFO - 'LOGNAME': 'cltbld', 15:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:54 INFO - 'MOZ_NO_REMOTE': '1', 15:24:54 INFO - 'NO_EM_RESTART': '1', 15:24:54 INFO - 'PAGER': '/bin/cat', 15:24:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:54 INFO - 'PWD': '/builds/slave/test', 15:24:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:54 INFO - 'SHELL': '/bin/bash', 15:24:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYWre2WoGg/Listeners', 15:24:54 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:24:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:54 INFO - 'USER': 'cltbld', 15:24:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:54 INFO - 'XPC_FLAGS': '0x0', 15:24:54 INFO - 'XPC_SERVICE_NAME': '0', 15:24:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:24:54 INFO - Cleaning up... 15:24:54 INFO - Return code: 0 15:24:54 INFO - Running post-action listener: _resource_record_post_action 15:24:54 INFO - Running post-action listener: _start_resource_monitoring 15:24:54 INFO - Starting resource monitoring. 15:24:54 INFO - [mozharness: 2016-12-16 23:24:54.986387Z] Finished create-virtualenv step (success) 15:24:54 INFO - [mozharness: 2016-12-16 23:24:54.987357Z] Running install step. 15:24:54 INFO - Running pre-action listener: _resource_record_pre_action 15:24:54 INFO - Running main action method: install 15:24:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:24:55 INFO - Reading from file tmpfile_stdout 15:24:55 INFO - Detecting whether we're running mozinstall >=1.0... 15:24:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:24:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:24:55 INFO - Reading from file tmpfile_stdout 15:24:55 INFO - Output received: 15:24:55 INFO - Usage: mozinstall [options] installer 15:24:55 INFO - Options: 15:24:55 INFO - -h, --help show this help message and exit 15:24:55 INFO - -d DEST, --destination=DEST 15:24:55 INFO - Directory to install application into. [default: 15:24:55 INFO - "/builds/slave/test"] 15:24:55 INFO - --app=APP Application being installed. [default: firefox] 15:24:55 INFO - mkdir: /builds/slave/test/build/application 15:24:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:24:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:25:26 INFO - Reading from file tmpfile_stdout 15:25:26 INFO - Output received: 15:25:26 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:25:26 INFO - Running post-action listener: _resource_record_post_action 15:25:26 INFO - [mozharness: 2016-12-16 23:25:26.040476Z] Finished install step (success) 15:25:26 INFO - [mozharness: 2016-12-16 23:25:26.040612Z] Running run-tests step. 15:25:26 INFO - Running pre-action listener: _resource_record_pre_action 15:25:26 INFO - Running main action method: run_tests 15:25:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:25:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:25:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:25:26 INFO - 2016-12-16 15:25:26.054 screenresolution[1621:7608] starting screenresolution argv=screenresolution get 15:25:26 INFO - 2016-12-16 15:25:26.057 screenresolution[1621:7608] Display 0: 1600x1200x32@60 15:25:26 INFO - 2016-12-16 15:25:26.063 screenresolution[1622:7611] starting screenresolution argv=screenresolution list 15:25:26 INFO - Available Modes on Display 0 15:25:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:25:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:25:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:25:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:25:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:25:26 INFO - Intel Iris: 15:25:26 INFO - Chipset Model: Intel Iris 15:25:26 INFO - Type: GPU 15:25:26 INFO - Bus: Built-In 15:25:26 INFO - VRAM (Dynamic, Max): 1536 MB 15:25:26 INFO - Vendor: Intel (0x8086) 15:25:26 INFO - Device ID: 0x0a2e 15:25:26 INFO - Revision ID: 0x0009 15:25:26 INFO - Displays: 15:25:26 INFO - Display: 15:25:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:25:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:25:26 INFO - Display Serial Number: 1600x1200 60 15:25:26 INFO - Main Display: Yes 15:25:26 INFO - Mirror: Off 15:25:26 INFO - Online: Yes 15:25:26 INFO - Rotation: Supported 15:25:26 INFO - Return code: 0 15:25:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:25:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:25:26 INFO - Python 2.7.3 15:25:26 INFO - Return code: 0 15:25:26 INFO - grabbing minidump binary from tooltool 15:25:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:26 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 0x1019fbd30>, '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 0x101861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:25:26 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 15:25:26 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 15:25:26 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 15:25:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:25:26 INFO - Return code: 0 15:25:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:25:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:25:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:25:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:25:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:25:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 15:25:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0104 --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 15:25:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wgzk4B55Cz/Render', 15:25:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:26 INFO - 'HOME': '/Users/cltbld', 15:25:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:26 INFO - 'LOGNAME': 'cltbld', 15:25:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:25:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:25:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:26 INFO - 'MOZ_NO_REMOTE': '1', 15:25:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:25:26 INFO - 'NO_EM_RESTART': '1', 15:25:26 INFO - 'PAGER': '/bin/cat', 15:25:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:26 INFO - 'PWD': '/builds/slave/test', 15:25:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:25:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:26 INFO - 'SHELL': '/bin/bash', 15:25:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYWre2WoGg/Listeners', 15:25:26 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:25:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:26 INFO - 'USER': 'cltbld', 15:25:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:26 INFO - 'XPC_FLAGS': '0x0', 15:25:26 INFO - 'XPC_SERVICE_NAME': '0', 15:25:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 15:25:26 INFO - mozversion application_buildid: 20161216133950 15:25:26 INFO - mozversion application_changeset: 3ddf24a98ed75a69527322b771c870fe2694f5c1 15:25:26 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:25:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:25:26 INFO - mozversion application_name: Firefox 15:25:26 INFO - mozversion application_remotingname: firefox-dev 15:25:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:25:26 INFO - mozversion application_vendor: Mozilla 15:25:26 INFO - mozversion application_version: 52.0a2 15:25:26 INFO - mozversion platform_buildid: 20161216133950 15:25:26 INFO - mozversion platform_changeset: 3ddf24a98ed75a69527322b771c870fe2694f5c1 15:25:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:25:26 INFO - mozversion platform_version: 52.0a2 15:25:26 INFO - using testdate: 1481930726 15:25:26 INFO - actual date: 1481930726 15:25:26 INFO - starting webserver on 'localhost:49244' 15:25:26 INFO - SUITE-START | Running 2 tests 15:25:26 INFO - TEST-START | dromaeo_css 15:25:26 INFO - operating with platform_type : mac_ 15:25:26 INFO - Initialising browser for dromaeo_css test... 15:25:26 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpP3d0cg/profile http://localhost:49244/getInfo.html) 15:25:29 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 15:25:29 INFO - PROCESS | 1632 | colorDepth:24 15:25:29 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 15:25:29 INFO - PROCESS | 1632 | __metrics 15:25:29 INFO - PROCESS | 1632 | 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" 15:25:29 INFO - PROCESS | 1632 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:25:30 INFO - TEST-INFO | 1632: exit 0 15:25:30 INFO - Browser initialized. 15:25:30 INFO - Running cycle 1/1 for dromaeo_css test... 15:25:30 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpP3d0cg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:25:42 INFO - PROCESS | 1634 | RSS: Main: 222748672 15:25:42 INFO - PROCESS | 1634 | 15:26:33 INFO - PROCESS | 1634 | 1481930793990 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] 15:28:48 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 15:28:50 INFO - PROCESS | 1634 | RSS: Main: 399761408 15:28:50 INFO - PROCESS | 1634 | 15:31:53 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 15:31:54 INFO - PROCESS | 1634 | RSS: Main: 346849280 15:31:54 INFO - PROCESS | 1634 | 15:34:56 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 15:34:57 INFO - PROCESS | 1634 | RSS: Main: 319803392 15:34:57 INFO - PROCESS | 1634 | 15:37:59 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 15:38:00 INFO - PROCESS | 1634 | RSS: Main: 320712704 15:38:00 INFO - PROCESS | 1634 | 15:41:04 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 15:41:04 INFO - PROCESS | 1634 | RSS: Main: 311492608 15:41:04 INFO - PROCESS | 1634 | 15:44:07 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 15:44:08 INFO - PROCESS | 1634 | RSS: Main: 360939520 15:44:08 INFO - PROCESS | 1634 | 15:44:08 INFO - PROCESS | 1634 | __start_tp_report 15:44:08 INFO - PROCESS | 1634 | _x_x_mozilla_page_load 15:44:08 INFO - PROCESS | 1634 | _x_x_mozilla_page_load_details 15:44:08 INFO - PROCESS | 1634 | |i|pagename|runs| 15:44:08 INFO - PROCESS | 1634 | |0;dojo.html;2795.510785612518;3336.549565808615;3345.882894098707;3363.0247228303792;3367.882124125656;5598.468145526173;5634.915476267859;5675.318961724599;5724.284464441945;5731.025725626649;5616.803411880585;5707.115397113444;5725.4847063764255;5748.913766293509;5768.653880767157;5175.715335656547;5219.00838840621;5240.737963101848;5243.161094224924;5297.576193904479;5120.129577971735;5136.024155410479;5136.5890728741615;5197.7920883164625;5322.733863306838;5221.190715439113;5221.477852214767;5230.398504172012;5230.92153617696;5231.790728370861;5214.374275086995;5239.502247286521;5239.685618862843;5240.9213861792105;5243.737813109367;5054.039732450822;5164.948350516484;5169.147090730025;5180.792768289264;5225.712585807779;5734.4265573442735;5742.138679198112;5751.884962300731;5763.337216220105;5766.0486019806685;6094.748796175517;6124.208312778138;6128.411459112332;6130.092410195535;6136.286473208055;6210.72051757672;6229.37706229378;6254.655993920333;6274.592151510183;6282.842928926768;5740.167675687044;5747.3677895431465;5747.56893233005;5756.1653560233535;5785.623934444247;5299.5760339172775;6208.286047104641;6278.30938596754;6341.968290158566;6354.17395738551;6012.909806352908;6019.187409699634;6025.879482410327;6062.818115456544;6063.454289114001;6227.19046523949;6230.968845155745;6240.5319601030105;6253.062040693932;6271.843203919939;6022.2171117754415;6225.688715564294;6225.8132256032395;6229.470495007885;6245.063240513869;5810.82567522975;6079.148919151322;6124.991279909952;6135.53983451234;6144.938550614526;4366.585174408426;4488.842890498817;4604.1252162089095;4863.124637565202;4864;4378.6278166356215;4409.11817636474;4414.984553552701;4422.358757980075;4437.534058923767;5556.77772889088;5609.747561359749;5626.324841019104;5633.464820842013;5676.517496012885;6113.506711826627;6160.44555989963;6184.474319682788;6202.906956395657;6251.187345645037;5758.74085666146;5768.826935191951;5770.538356931435;5774.105013722879;5775.711214439345;5284.154535274338;5320.574354051667;5355.919661205007;5373.462653734673;5399.136138217867;6210.5652604317265;6213.285472170577;6224.595401298901;6283.497320214463;6288.905666415099;5166.4316925137455;6310.936890631218;6312.053192021233;6312.747490100344;6326.398992095744;6234.878556489008;6272.153259309935;6277.5605707600025;6298.433141017147;6306.369363063657;5946.137810017437;6059.878802424015;6152.815415537541;6156.984097623842;6161.075838624242;6142.744345496893;6159.538034647329;6160.876782464416;6235.46998505114;6278.995360742259;5379.5965302603245;5648.915266271091;6066.939330606814;6080.847978800565;6091.543134264859;6017.759289628366;6019.337872834072;6046.606970546901;6087.69561521931;6092.390760923872;5672.2058911751565;5696.629719068247;5824.271966004249;5826.621269617462;5913.586048976703;6089.482393996383;6101.938980610137;6112.169143789106;6130.2030736004035;6136.110264011782;5706.686132262766;5761.164631128547;5766.365699773106;5780.248567686174;5863.618864773776 15:44:08 INFO - PROCESS | 1634 | |1;ext.html;497.9178435558133;513.3480479790666;516.4396629656821;527.6359312074666;553.5405613340928;29558.556621650692;38760;39712.01439928003;39716.60283397165;41081.58918410815;40724;40940.59059409405;41072;41082;41106.794466027626;8262.83474330513;9171.312151588625;9321.906780932171;9339.719808405725;9345.626174952993;8253.628586713583;10916.01755841974;11248.043916267086;11260.099192064656;11376.317420954727;11179.608713695023;11207.66377008691;11212;11246.718832029183;11251.493682784294;11010.284331518465;11061.778764424707;11093.72265693356;11095.1123910087;11107.278026928305;11085.556577736961;11131.276467029698;11190.888091119148;11219.887801121966;11242.213045086766;38357;38515.42226866599;38597.61402385996;38629.61370386288;38736.45054197829;3245.334706385185;3305.074579117851;3399.558057452515;3671.81640917953;3830.540335159799;27538.724612754017;27569.862150689118;27685.446291074266;28431.715682843114;29127.126186214235;3004.5410556093248;3261;3315.6850099240532;3330.733541316689;3336.4995250712345;437735;438532;438626;441157.9999999968;442463;46912.530874691496;56595.717021415454;56986.7150664244;56987.430125699044;57023;59838.70080649656;59871.700641496514;59964.70017649884;59996.40003599908;60009.399906000384;12748.171368860994;12988.480460781651;12993.415296311689;12998.610041698765;13014.21914685122;9087.136722011399;9289.396189247676;9342.48616326095;9905.851412228822;9925.2059835213;16992.511215821043;20110;20163.89098599562;20442.795572044386;20686.482837929172;7946.960265198637;7978.960105199553;8029.036515618163;8126.75619731409;8128.9593552031865;850.459957926721;964.9855252171224;1014.5688082564881;1020.8315627921459;1039.7920415916847;29018;29163;29428.264293392836;29950.700492995227;30136.84931575328;973.5764942250144;1005.1205195453978;1027.3527677563088;1064.9882611519088;1079.8542196803487;14595.489157879423;15179.316930738143;15180.696386072437;15377.550612945715;15666.921665391601;625474;634150;634806;635490;635923;56060.719696401255;56391.71804140953;56472.43527564754;56603.15095273574;56757.14864277039;1193.021722187815;1241.1064033895761;1314.6319030671427;1332.3737843213673;1348.885344745688;23885.402864928517;29194.56208156837;29837.850810745807;30410.847945760128;30683.846580766953;47121.057578848915;52626;54335.72832135814;54473.455265446835;57330.85338293294;606743.9325606688;607869;613868;615298;616160;21790.78209217951;25637.743622563536;26352.34119147037;26627.201183964513;41856.79071604623;44894.551054490345;45031.77484112558;45505.54494455013;45803.854903627675;45813.541864580926;36372.454413183295;36609.25833748133;37387.43918841274;38240.80879595584;38776.2244755109;29832.403351932404;36693;36844.63155368412;36865.815670921475;36929.63070369369;36303.636963631085;36607.45088823723;36990.815045924595;37094.629053710196;37405.812970934974;29791;31609.683903160672;32040;32992.67007329992;33679.83160084184;24649.383765406008;24690;24865.62701559442;25422.618660719734;25481.235562933143 15:44:08 INFO - PROCESS | 1634 | |2;jquery.html;339.61453749993757;353.33572882979996;372.4171671334362;380.5851621732313;405.24219709143904;14296.28518574071;14718.63203419915;15759.81801364898;15839.287232074556;20205.595888082265;18357.90821045896;19122.61754764904;19881.602367952633;20062.097205625745;20078.598428031466;5492.972535137319;5681.687507187103;5687.744051517671;5788.942110578903;5816.82549523515;4633.9073218535605;5641.210230567704;5720.628159169662;5760.654360738348;5781.103928891029;5717.428257174291;5720.771169153229;5724.4561766632105;5725.971370143144;5744.310682718059;5644.604877658545;5726.942730572682;5937.821865344046;5970.432808883149;6032.3967603239325;5642.689652069134;5812.709364531756;5826.766929322822;5828.125781132864;5849.532037436995;13040.934795325962;13327.933360333136;13393.464261429532;13407.396667150002;13422.93288533561;2098.95802083959;2219.1345375303645;2257.3679369776487;2302.2057390200353;2309.0879102754484;9814.75463113428;9918.553665085017;10171.033751793568;10350.534225959775;10459;1804;1819.9171492961798;1862.3388697012501;1888.4051523769967;1914.5692219250695;111613;119041;119122;121823;128569.35715321364;7645.847083058307;9741.220702343795;9995.900040999642;10067.496625168713;11213.383263920563;12411.255324680547;12499.000079993579;12502.687432814253;12540.686482838002;12548.372581370895;6289.7169627366875;6811.739828131812;7032.613206273704;7117;7171.964140179266;7004.684789184499;7042.225355210922;7097.467689923174;7162.319579639926;7215.134183897966;3774.7923864187733;3893.3186692328727;3903.6291552302487;3917.236138952877;3937.547182074041;1609.798775153106;1863.1429542410642;1895.090356628826;1899.917047283063;1924.7594050743658;837.1419295222446;843.472829481574;865.5888452984782;911.0889110889111;913.8492148795507;19378.515537111685;20241.392758217276;20284.695729564068;20317.968203179564;20756.68864967027;799.836238273311;804.481109684262;805.1988271669726;839.7606682095574;925.666759966425;10436.530356133992;10738.8187299397;10751.408672523086;10777.83833242502;10787.514561844579;601877;606970;615507;632080;633691;122430.57251204737;128102.35948820197;129910;138154.92767608495;138982.52543686487;947.2516711797603;949.7008442340641;956.086936975201;966.6858271061933;971.0483725948469;16958;17474.47576572705;18134.90932545329;18157.36449224271;18559.16483758207;47509;49248;53497.19754203612;56407.71796140993;56530.43469565252;70777.6461117691;72505.91241131493;75329.6233518829;78066;78528;7847.269510067647;7967.840643186988;8081.15147909461;8223.79440513968;8374.53940033304;9250.537473126182;12350.814737779121;12693.682657933625;12880.613581592566;13261.939044876579;11059.281146725438;11061.834072488758;11138.88861111411;11473.368964706688;11516.654500365003;9070.546472676471;10971.396573188233;11105.666829995112;11113.833292500458;11324.773504530029;11378.886211137782;11403.429828508708;11563.6530904073;11642.41787910618;11663.65009049696;7973.481723687942;8025.398095142771;8054.637541310773;8228.629711663109;8389.203025712548;7598.620068996639;9775.902240977499;10982.725431864268;11280.153988451064;11319.490622922152 15:44:08 INFO - PROCESS | 1634 | |3;mootools.html;1282.608804314685;1337.8729020743028;1404.6699025728951;1522.9923850380746;1788.651213013463;7415.740449084283;7754.806129846756;8071.677132914677;8318;8345.833083338344;8158;8274.91725082749;8278.54468004261;8279.089300176976;8279.337652987762;2301.7583153768865;2327.1156960355042;2331.673565700795;2332.486852892361;2334.0663734506247;1990.5222746540871;2316.6872472216287;2357.1867705641525;2376.2039716694926;2379.7858192762646;2307.4231442139467;2316.7683231676865;2343.4375749820006;2349.1425629645228;2354.4702441950594;2292.266474728088;2308.3998160478313;2326.220716060122;2331.3122628824535;2336.3224664847176;2331.8717470539113;2356.1282325539487;2357.233898982837;2359.5162991586685;2359.787619114271;7505.62471876404;7803.960980195063;8288.461250018729;8306.667733290662;8309.45988510751;5011.523905228997;5212.1139406300645;5220.451852555503;5258.974499972507;5261.5264626183825;3363.1928337198897;3498.4227602445567;3526.806025668587;3573.1781690211396;3580.4987301777774;1053.3311347294523;1071.9035286824146;1084.8589683341115;1090.43297485308;1098.6978580890238;10796.5681372745;10849.457527123612;10946.945265273622;10982.341059536453;10993.61522346722;4273.145370925765;4398.538153493901;4509.594136527729;4513.909721805546;4517.390152329459;8697.521636310064;8700.52147131901;8707.216350528483;8716.825663486821;8732.214100730966;5135.820246291437;5139.203423469368;5145.716985565755;5148.124818780741;5150.639455238097;4062.979685101556;4315.417418648505;4434.26834572292;4457.888552786207;4841.806327746851;3591.892243232655;3624.7281453891064;3725.5343082114737;3737.2712321097124;3744.981275093607;1138.599522587206;1140.560884059643;1142.4402042999036;1158.1892675127444;1163.2787671643466;803.1687203744096;880.7489865388334;894.1952242981368;909.3043821476575;914.1178762494092;5428.972855135699;5457.945420545823;5541.33503979517;5659.773609055592;5737.655740655574;917.8944421391492;954.9474778887228;974.0503009565617;985.9852102218473;1030.2376241581176;3676.8713095041544;3756.6619004289746;3770.1517158639085;3846.3268927937497;3871.496705428276;9803.705888823346;9979.950100249453;10010.699679009642;10270.024347686958;10285.794284114276;10001.34991225568;10029.548670309854;10245.129164020993;10275.897241027644;10276.743081423025;7514.248575142551;7735.110462296908;7902.802429939183;7916.920830791619;7929.643166057426;5302.628815982998;5427.294451721251;5567.192757050124;5687.971560142173;5721.771129154788;9024.954875225581;9063.32025098107;9360;9434.622615095594;9476.147146756824;8585.656573737231;8908.510031948304;9049.864252036094;9288.024757400375;9399.248060155307;5994.430529099728;7766.417518686008;7781.06627204739;7793.961030194812;7852.13626501073;7817.609119543887;7938.364930805637;7994.200579941959;8010.279074883289;8018.398620103399;6995.545289556163;7043.894341584983;7064.222935476991;7107.324804143598;7107.644617768988;6855.314468553105;6890.724371025103;6901.689423976033;6903.482238832007;6973.302669733189;6592;6595.571287866273;6598.967005164944;6672.266050734513;6679.265280819203;4599.954000460087;4655.23188673865;4670.556297151765;4739.1706451371565;4767.427908650984;4746.928796067993;4764.523547645346;4806.2310030394965;4868.318435418973;4925.38432695913 15:44:08 INFO - PROCESS | 1634 | |4;prototype.html;296.39831142780156;307.07417137330947;308.32015406029706;313.83723305154393;318.69086985623966;4487.977560112199;4714.080754252922;5049.4698056704065;5096.668716533427;5128.53836656553;4641.443026836776;4654.672663668166;4706.90586188276;4970.887372866536;4998.775055122519;3341.74936879735;3474.4462819827468;3673.338799016176;3827.5789663137034;3870.612938706135;3570.1967057412135;3750.887473375803;3855.5541671873048;3909.257241124177;3917.8628747993826;3215.099772063826;3785.432185172219;3796.9240615187678;3839.7696138231654;3841.3661745811905;3602.4776407420777;3670.596234414212;3678.5401824771902;3709.99450334503;3857;3378.831058447068;3378.9557317822855;3384.4415671414185;3411.4371128763723;3422;4029.1135950090934;4339;4827.565519103263;4867.537583929557;4875.317455556225;4181.540030596632;4394.560543945612;4400.603945644875;4405.22908491016;4417.271150260203;4920.843601753585;4934.333864928225;5253.711045892474;5318;5352.357717073937;3573.8391772370305;3744.2324323513612;3747.2880152770886;3748.775073495599;3775.565809931838;4663.510331415187;4875.804967801319;5544.84136897327;5579.330480342344;5619.466150715716;3358.596968363812;3686.6681998619715;3706.4440333950124;3721.9441708374397;3728.4780130781714;3708.6847617952235;3761;3780.3006443807985;3796.4115562087923;3802.7338086334244;3660.8352624131444;3683.7605555638797;4365.236083685342;4378.2119218540965;4386.342048692722;4534.841280555164;4975.328330675385;5395.271638328775;5402.648827826179;5404.6218214355085;1080.303204433152;1148.6445993727482;1217.1905682720892;1223.969400764988;1232.2421710648066;804.3283857978537;825.2490233887133;829.3199576347458;834.9123342049119;839.0812060793421;4118.951975538081;4204.285271503852;4366.388705581221;4427.380166776654;4503.617192538671;4829.348038014893;4943.220440641367;5444.6188766785945;5476.7535460903555;5491.780328786884;4448.822047118144;5017.3226614406585;5021.347224860745;5023.020511000393;5032.458608402733;2285.851419657717;2313.61183290028;2587;2648.854313012803;2737.6988531261545;4572.337011133433;4694.389729335165;5308.849915784113;5317.3147560482485;5337.306150200449;4784.760761961888;5275.28553220205;5282.603804714662;5291.920621190685;5364.624476286701;4559.3844830948065;4931.9753401232765;4949.752512374295;5134.5378915897;5339.7063161526485;4281.486221653421;4871.171900776947;4890.266460031024;5061.544460998528;5148.871278217925;4656.8137274508645;4814.783334750015;5035.370578677665;5074.720890351066;5105.974470127626;5247.895042099213;5662.518685911579;5703.973284808609;5710.486056254957;5732.570057245806;3334.0831271400557;3680.8343624536424;3697.0572504011307;3717.591064982904;3750.7937063461773;5046.6719663221775;5324.920126198032;5530.101278598659;5535.916961245504;5582.246396736551;5164.302819119371;5320;5339.279197308314;5349.893002140013;5380;5192.2605927013365;5316.175992721134;5328.25404443386;5341.41244463101;5361.490658387447;4569.81720731167;4948.035133149001;5200.63595548308;5241.139395514538;5250.684958902478;4369.951211709231;4529.977350113229;4798.904021919472;5220.895582088413;5306.018386598492;4659.555537783298;4933.457319694902;5075.720835354091;5106.821261255838;5225.947740522546 15:44:08 INFO - PROCESS | 1634 | |5;yui.html;683.911091558097;688.1329524798753;700.4676445901115;753.600591686406;792.7185849023595;13824.930875345623;14334.498292559763;14342.784858227134;14378.640533986629;14385.712285754278;12427.440765165567;14030.859691403082;14658.97387182895;14683.192424416653;14972.401103955828;651.3161180760206;675.3921470676406;813.1299509524811;838.714836955435;839.7774589733725;679.2765704524691;801.9759407217764;818.8812622169781;833.8832563441093;839.0518713853337;871.477113731759;874.204473928725;874.7769318823691;875.8117004843957;879.3668558637803;689.1592257445908;714.4927101757759;863.2619110660421;865.0916537635458;866.3805379153904;635.1171871099176;654.7839213059726;659.1003280522101;661.4377778887955;663.1081195791701;14224.35990380425;14272;14277.14337139775;14283.000189986706;14296.142231466041;12682.175658582162;12813.35933203336;12942.352882355939;12955.546555870593;12972.156809807333;11859.881401185963;12064.091417215253;12120.151589388746;12210.450529726184;12650.114491985565;4304.935425968613;4324.740515569076;4425.424694789689;4494.842680506199;4511.548845115495;82088.58955705183;82132.3573528532;82247.5887620564;82807.7578836318;83574;9689.356848643072;9792.167665748491;10212.744681382876;10229.948850255701;10257.17942564593;18051.00719460416;18704.438866833745;18708.719369209473;18725.81274187268;18736.437906862815;7270.63646817657;7271.272872712792;7284.599347035964;7358.889616655754;7482.027336446227;3953.6441720245316;4027.9597204027586;4174.332106862948;4226.936595951063;4260.382244574581;2872.6785678587667;3186.2193762528327;3292.489664102068;3296.7197788188273;3303.702666760003;614.5483069943588;630.8265227062503;634.1407392982535;646.6928209100639;649.870025994803;543.8939406815633;549.9367572729108;550.5292974506739;593.4006653280131;608.7504123309421;7258.745943891939;8219.54792486407;8526.829463410697;8626.698065567674;8999.505027223566;602.4668168670757;609.6951524237882;618.4588485075559;624.9375062493714;642.1331202876173;4027.7381970171846;4247.426597409372;4286.421333120051;4334.605680274301;4368.082702632482;89199;93731.53134234421;96230;100462.49768751109;106434;59432;63436.36563634305;77842.2215777835;83404.58297708472;84172.15827841766;80179.19820801834;80498.59750701209;80921.59539202266;82352;84286.57856710923;9122.452216670621;9426.481543515172;9622.473750986614;9739.561719722771;9759.165439616605;47468.52531474641;51182.720431989495;51491.48508515017;52699;52874.20688689596;51514;53416.46583534115;72363.27636723565;72631;74397.62801185959;12255;12348.814767778671;12407.205212960493;12587.622371328875;12608.87391126077;13806.102603330752;14316.06945548536;14404.351804168627;14457;14519.92740036293;10847.511861966073;12841.101122921305;13201.60395188146;13304.334783260992;13394.464221431033;12456.937715311366;12472.438740256892;12669.239845609292;12855.742885142432;13319.134256273312;12891.290978996245;12915.935420322838;12921.741565168455;12925.418356174183;13036.739265214832;10652.786944261226;10851.891481085404;11136.99767020972;11158;11456.82814757796;11199.776004479701;11373.374464404536;11405.771884562097;11517.82723259135;11678.474468648761 15:44:08 INFO - PROCESS | 1634 | __end_tp_report 15:44:08 INFO - PROCESS | 1634 | __start_cc_report 15:44:08 INFO - PROCESS | 1634 | _x_x_mozilla_cycle_collect,2152 15:44:08 INFO - PROCESS | 1634 | __end_cc_report 15:44:08 INFO - PROCESS | 1634 | __startTimestamp1481931848122__endTimestamp 15:44:08 INFO - PROCESS | 1634 | 15:44:08 INFO - PROCESS | 1634 | ------- Summary: start ------- 15:44:08 INFO - PROCESS | 1634 | Number of tests: 6 15:44:08 INFO - PROCESS | 1634 | 15:44:08 INFO - PROCESS | 1634 | [#0] dojo.html Cycles:165 Average:5685.60 Median:5775.71 stddev:652.43 (11.3%) stddev-sans-first:613.77 15:44:08 INFO - PROCESS | 1634 | Values: 2795.5 3336.5 3345.9 3363.0 3367.9 5598.5 5634.9 5675.3 5724.3 5731.0 5616.8 5707.1 5725.5 5748.9 5768.7 5175.7 5219.0 5240.7 5243.2 5297.6 5120.1 5136.0 5136.6 5197.8 5322.7 5221.2 5221.5 5230.4 5230.9 5231.8 5214.4 5239.5 5239.7 5240.9 5243.7 5054.0 5164.9 5169.1 5180.8 5225.7 5734.4 5742.1 5751.9 5763.3 5766.0 6094.7 6124.2 6128.4 6130.1 6136.3 6210.7 6229.4 6254.7 6274.6 6282.8 5740.2 5747.4 5747.6 5756.2 5785.6 5299.6 6208.3 6278.3 6342.0 6354.2 6012.9 6019.2 6025.9 6062.8 6063.5 6227.2 6231.0 6240.5 6253.1 6271.8 6022.2 6225.7 6225.8 6229.5 6245.1 5810.8 6079.1 6125.0 6135.5 6144.9 4366.6 4488.8 4604.1 4863.1 4864.0 4378.6 4409.1 4415.0 4422.4 4437.5 5556.8 5609.7 5626.3 5633.5 5676.5 6113.5 6160.4 6184.5 6202.9 6251.2 5758.7 5768.8 5770.5 5774.1 5775.7 5284.2 5320.6 5355.9 5373.5 5399.1 6210.6 6213.3 6224.6 6283.5 6288.9 5166.4 6310.9 6312.1 6312.7 6326.4 6234.9 6272.2 6277.6 6298.4 6306.4 5946.1 6059.9 6152.8 6157.0 6161.1 6142.7 6159.5 6160.9 6235.5 6279.0 5379.6 5648.9 6066.9 6080.8 6091.5 6017.8 6019.3 6046.6 6087.7 6092.4 5672.2 5696.6 5824.3 5826.6 5913.6 6089.5 6101.9 6112.2 6130.2 6136.1 5706.7 5761.2 5766.4 5780.2 5863.6 15:44:08 INFO - PROCESS | 1634 | 15:44:08 INFO - PROCESS | 1634 | [#1] ext.html Cycles:180 Average:68968.38 Median:20258.22 stddev:152031.33 (750.5%) stddev-sans-first:152370.90 15:44:08 INFO - PROCESS | 1634 | Values: 497.9 513.3 516.4 527.6 553.5 29558.6 38760.0 39712.0 39716.6 41081.6 40724.0 40940.6 41072.0 41082.0 41106.8 8262.8 9171.3 9321.9 9339.7 9345.6 8253.6 10916.0 11248.0 11260.1 11376.3 11179.6 11207.7 11212.0 11246.7 11251.5 11010.3 11061.8 11093.7 11095.1 11107.3 11085.6 11131.3 11190.9 11219.9 11242.2 38357.0 38515.4 38597.6 38629.6 38736.5 3245.3 3305.1 3399.6 3671.8 3830.5 27538.7 27569.9 27685.4 28431.7 29127.1 3004.5 3261.0 3315.7 3330.7 3336.5 437735.0 438532.0 438626.0 441158.0 442463.0 46912.5 56595.7 56986.7 56987.4 57023.0 59838.7 59871.7 59964.7 59996.4 60009.4 12748.2 12988.5 12993.4 12998.6 13014.2 9087.1 9289.4 9342.5 9905.9 9925.2 16992.5 20110.0 20163.9 20442.8 20686.5 7947.0 7979.0 8029.0 8126.8 8129.0 850.5 965.0 1014.6 1020.8 1039.8 29018.0 29163.0 29428.3 29950.7 30136.8 973.6 1005.1 1027.4 1065.0 1079.9 14595.5 15179.3 15180.7 15377.6 15666.9 625474.0 634150.0 634806.0 635490.0 635923.0 56060.7 56391.7 56472.4 56603.2 56757.1 1193.0 1241.1 1314.6 1332.4 1348.9 23885.4 29194.6 29837.9 30410.8 30683.8 47121.1 52626.0 54335.7 54473.5 57330.9 606743.9 607869.0 613868.0 615298.0 616160.0 21790.8 25637.7 26352.3 26627.2 41856.8 44894.6 45031.8 45505.5 45803.9 45813.5 36372.5 36609.3 37387.4 38240.8 38776.2 29832.4 36693.0 36844.6 36865.8 36929.6 36303.6 36607.5 36990.8 37094.6 37405.8 29791.0 31609.7 32040.0 32992.7 33679.8 24649.4 24690.0 24865.6 25422.6 25481.2 15:44:08 INFO - PROCESS | 1634 | 15:44:08 INFO - PROCESS | 1634 | [#2] jquery.html Cycles:180 Average:34855.62 Median:2136.95 stddev:103251.05 (4831.7%) stddev-sans-first:103508.17 15:44:08 INFO - PROCESS | 1634 | Values: 339.6 353.3 372.4 380.6 405.2 14296.3 14718.6 15759.8 15839.3 20205.6 18357.9 19122.6 19881.6 20062.1 20078.6 5493.0 5681.7 5687.7 5788.9 5816.8 4633.9 5641.2 5720.6 5760.7 5781.1 5717.4 5720.8 5724.5 5726.0 5744.3 5644.6 5726.9 5937.8 5970.4 6032.4 5642.7 5812.7 5826.8 5828.1 5849.5 13040.9 13327.9 13393.5 13407.4 13422.9 2099.0 2219.1 2257.4 2302.2 2309.1 9814.8 9918.6 10171.0 10350.5 10459.0 1804.0 1819.9 1862.3 1888.4 1914.6 111613.0 119041.0 119122.0 121823.0 128569.4 7645.8 9741.2 9995.9 10067.5 11213.4 12411.3 12499.0 12502.7 12540.7 12548.4 6289.7 6811.7 7032.6 7117.0 7172.0 7004.7 7042.2 7097.5 7162.3 7215.1 3774.8 3893.3 3903.6 3917.2 3937.5 1609.8 1863.1 1895.1 1899.9 1924.8 837.1 843.5 865.6 911.1 913.8 19378.5 20241.4 20284.7 20318.0 20756.7 799.8 804.5 805.2 839.8 925.7 10436.5 10738.8 10751.4 10777.8 10787.5 601877.0 606970.0 615507.0 632080.0 633691.0 122430.6 128102.4 129910.0 138154.9 138982.5 947.3 949.7 956.1 966.7 971.0 16958.0 17474.5 18134.9 18157.4 18559.2 47509.0 49248.0 53497.2 56407.7 56530.4 70777.6 72505.9 75329.6 78066.0 78528.0 7847.3 7967.8 8081.2 8223.8 8374.5 9250.5 12350.8 12693.7 12880.6 13261.9 11059.3 11061.8 11138.9 11473.4 11516.7 9070.5 10971.4 11105.7 11113.8 11324.8 11378.9 11403.4 11563.7 11642.4 11663.7 7973.5 8025.4 8054.6 8228.6 8389.2 7598.6 9775.9 10982.7 11280.2 11319.5 15:44:08 INFO - PROCESS | 1634 | 15:44:08 INFO - PROCESS | 1634 | [#3] mootools.html Cycles:180 Average:5394.58 Median:5137.51 stddev:2950.65 (57.4%) stddev-sans-first:2942.75 15:44:08 INFO - PROCESS | 1634 | Values: 1282.6 1337.9 1404.7 1523.0 1788.7 7415.7 7754.8 8071.7 8318.0 8345.8 8158.0 8274.9 8278.5 8279.1 8279.3 2301.8 2327.1 2331.7 2332.5 2334.1 1990.5 2316.7 2357.2 2376.2 2379.8 2307.4 2316.8 2343.4 2349.1 2354.5 2292.3 2308.4 2326.2 2331.3 2336.3 2331.9 2356.1 2357.2 2359.5 2359.8 7505.6 7804.0 8288.5 8306.7 8309.5 5011.5 5212.1 5220.5 5259.0 5261.5 3363.2 3498.4 3526.8 3573.2 3580.5 1053.3 1071.9 1084.9 1090.4 1098.7 10796.6 10849.5 10946.9 10982.3 10993.6 4273.1 4398.5 4509.6 4513.9 4517.4 8697.5 8700.5 8707.2 8716.8 8732.2 5135.8 5139.2 5145.7 5148.1 5150.6 4063.0 4315.4 4434.3 4457.9 4841.8 3591.9 3624.7 3725.5 3737.3 3745.0 1138.6 1140.6 1142.4 1158.2 1163.3 803.2 880.7 894.2 909.3 914.1 5429.0 5457.9 5541.3 5659.8 5737.7 917.9 954.9 974.1 986.0 1030.2 3676.9 3756.7 3770.2 3846.3 3871.5 9803.7 9980.0 10010.7 10270.0 10285.8 10001.3 10029.5 10245.1 10275.9 10276.7 7514.2 7735.1 7902.8 7916.9 7929.6 5302.6 5427.3 5567.2 5688.0 5721.8 9025.0 9063.3 9360.0 9434.6 9476.1 8585.7 8908.5 9049.9 9288.0 9399.2 5994.4 7766.4 7781.1 7794.0 7852.1 7817.6 7938.4 7994.2 8010.3 8018.4 6995.5 7043.9 7064.2 7107.3 7107.6 6855.3 6890.7 6901.7 6903.5 6973.3 6592.0 6595.6 6599.0 6672.3 6679.3 4600.0 4655.2 4670.6 4739.2 4767.4 4746.9 4764.5 4806.2 4868.3 4925.4 15:44:08 INFO - PROCESS | 1634 | 15:44:08 INFO - PROCESS | 1634 | [#4] prototype.html Cycles:180 Average:4225.65 Median:4678.95 stddev:1287.24 (27.5%) stddev-sans-first:1256.61 15:44:08 INFO - PROCESS | 1634 | Values: 296.4 307.1 308.3 313.8 318.7 4488.0 4714.1 5049.5 5096.7 5128.5 4641.4 4654.7 4706.9 4970.9 4998.8 3341.7 3474.4 3673.3 3827.6 3870.6 3570.2 3750.9 3855.6 3909.3 3917.9 3215.1 3785.4 3796.9 3839.8 3841.4 3602.5 3670.6 3678.5 3710.0 3857.0 3378.8 3379.0 3384.4 3411.4 3422.0 4029.1 4339.0 4827.6 4867.5 4875.3 4181.5 4394.6 4400.6 4405.2 4417.3 4920.8 4934.3 5253.7 5318.0 5352.4 3573.8 3744.2 3747.3 3748.8 3775.6 4663.5 4875.8 5544.8 5579.3 5619.5 3358.6 3686.7 3706.4 3721.9 3728.5 3708.7 3761.0 3780.3 3796.4 3802.7 3660.8 3683.8 4365.2 4378.2 4386.3 4534.8 4975.3 5395.3 5402.6 5404.6 1080.3 1148.6 1217.2 1224.0 1232.2 804.3 825.2 829.3 834.9 839.1 4119.0 4204.3 4366.4 4427.4 4503.6 4829.3 4943.2 5444.6 5476.8 5491.8 4448.8 5017.3 5021.3 5023.0 5032.5 2285.9 2313.6 2587.0 2648.9 2737.7 4572.3 4694.4 5308.8 5317.3 5337.3 4784.8 5275.3 5282.6 5291.9 5364.6 4559.4 4932.0 4949.8 5134.5 5339.7 4281.5 4871.2 4890.3 5061.5 5148.9 4656.8 4814.8 5035.4 5074.7 5106.0 5247.9 5662.5 5704.0 5710.5 5732.6 3334.1 3680.8 3697.1 3717.6 3750.8 5046.7 5324.9 5530.1 5535.9 5582.2 5164.3 5320.0 5339.3 5349.9 5380.0 5192.3 5316.2 5328.3 5341.4 5361.5 4569.8 4948.0 5200.6 5241.1 5250.7 4370.0 4530.0 4798.9 5220.9 5306.0 4659.6 4933.5 5075.7 5106.8 5225.9 15:44:08 INFO - PROCESS | 1634 | 15:44:08 INFO - PROCESS | 1634 | [#5] yui.html Cycles:180 Average:18963.92 Median:49325.62 stddev:26554.44 (53.8%) stddev-sans-first:26593.46 15:44:08 INFO - PROCESS | 1634 | Values: 683.9 688.1 700.5 753.6 792.7 13824.9 14334.5 14342.8 14378.6 14385.7 12427.4 14030.9 14659.0 14683.2 14972.4 651.3 675.4 813.1 838.7 839.8 679.3 802.0 818.9 833.9 839.1 871.5 874.2 874.8 875.8 879.4 689.2 714.5 863.3 865.1 866.4 635.1 654.8 659.1 661.4 663.1 14224.4 14272.0 14277.1 14283.0 14296.1 12682.2 12813.4 12942.4 12955.5 12972.2 11859.9 12064.1 12120.2 12210.5 12650.1 4304.9 4324.7 4425.4 4494.8 4511.5 82088.6 82132.4 82247.6 82807.8 83574.0 9689.4 9792.2 10212.7 10229.9 10257.2 18051.0 18704.4 18708.7 18725.8 18736.4 7270.6 7271.3 7284.6 7358.9 7482.0 3953.6 4028.0 4174.3 4226.9 4260.4 2872.7 3186.2 3292.5 3296.7 3303.7 614.5 630.8 634.1 646.7 649.9 543.9 549.9 550.5 593.4 608.8 7258.7 8219.5 8526.8 8626.7 8999.5 602.5 609.7 618.5 624.9 642.1 4027.7 4247.4 4286.4 4334.6 4368.1 89199.0 93731.5 96230.0 100462.5 106434.0 59432.0 63436.4 77842.2 83404.6 84172.2 80179.2 80498.6 80921.6 82352.0 84286.6 9122.5 9426.5 9622.5 9739.6 9759.2 47468.5 51182.7 51491.5 52699.0 52874.2 51514.0 53416.5 72363.3 72631.0 74397.6 12255.0 12348.8 12407.2 12587.6 12608.9 13806.1 14316.1 14404.4 14457.0 14519.9 10847.5 12841.1 13201.6 13304.3 13394.5 12456.9 12472.4 12669.2 12855.7 13319.1 12891.3 12915.9 12921.7 12925.4 13036.7 10652.8 10851.9 11137.0 11158.0 11456.8 11199.8 11373.4 11405.8 11517.8 11678.5 15:44:08 INFO - PROCESS | 1634 | -------- Summary: end -------- 15:44:08 INFO - PROCESS | 1634 | 15:44:08 INFO - TEST-INFO | 1634: exit 0 15:44:08 INFO - TEST-OK | dromaeo_css | took 1122123ms 15:44:08 INFO - TEST-START | kraken 15:44:08 INFO - operating with platform_type : mac_ 15:44:08 INFO - Initialising browser for kraken test... 15:44:08 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpXL0Kcu/profile http://localhost:49244/getInfo.html) 15:44:11 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 15:44:11 INFO - PROCESS | 1641 | colorDepth:24 15:44:11 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 15:44:11 INFO - PROCESS | 1641 | __metrics 15:44:11 INFO - PROCESS | 1641 | 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" 15:44:11 INFO - PROCESS | 1641 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:44:12 INFO - TEST-INFO | 1641: exit 0 15:44:12 INFO - Browser initialized. 15:44:12 INFO - Running cycle 1/1 for kraken test... 15:44:12 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpXL0Kcu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:44:24 INFO - PROCESS | 1642 | RSS: Main: 222703616 15:44:24 INFO - PROCESS | 1642 | 15:44:27 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 15:44:28 INFO - PROCESS | 1642 | RSS: Main: 272998400 15:44:28 INFO - PROCESS | 1642 | 15:44:30 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 15:44:31 INFO - PROCESS | 1642 | RSS: Main: 283959296 15:44:31 INFO - PROCESS | 1642 | 15:44:34 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 15:44:35 INFO - PROCESS | 1642 | RSS: Main: 380284928 15:44:35 INFO - PROCESS | 1642 | 15:44:37 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 15:44:37 INFO - PROCESS | 1642 | RSS: Main: 284049408 15:44:37 INFO - PROCESS | 1642 | 15:44:39 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:44:40 INFO - PROCESS | 1642 | RSS: Main: 285036544 15:44:40 INFO - PROCESS | 1642 | 15:44:43 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 15:44:43 INFO - PROCESS | 1642 | RSS: Main: 328302592 15:44:43 INFO - PROCESS | 1642 | 15:44:46 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 15:44:47 INFO - PROCESS | 1642 | RSS: Main: 327573504 15:44:47 INFO - PROCESS | 1642 | 15:44:49 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 15:44:50 INFO - PROCESS | 1642 | RSS: Main: 324112384 15:44:50 INFO - PROCESS | 1642 | 15:44:52 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:44:52 INFO - PROCESS | 1642 | RSS: Main: 300777472 15:44:52 INFO - PROCESS | 1642 | 15:44:54 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:44:55 INFO - PROCESS | 1642 | RSS: Main: 303091712 15:44:55 INFO - PROCESS | 1642 | 15:44:57 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:44:58 INFO - PROCESS | 1642 | RSS: Main: 305086464 15:44:58 INFO - PROCESS | 1642 | 15:45:00 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:45:01 INFO - PROCESS | 1642 | RSS: Main: 324308992 15:45:01 INFO - PROCESS | 1642 | 15:45:04 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:45:05 INFO - PROCESS | 1642 | RSS: Main: 316944384 15:45:05 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 15:45:07 INFO - PROCESS | 1642 | RSS: Main: 360603648 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | __start_tp_report 15:45:07 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 15:45:07 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 15:45:07 INFO - PROCESS | 1642 | |i|pagename|runs| 15:45:07 INFO - PROCESS | 1642 | |0;ai-astar;116;102;111;115;114;114;114;115;115;115 15:45:07 INFO - PROCESS | 1642 | |1;audio-beat-detection;145;114;139;173;114;111;111;109;141;109 15:45:07 INFO - PROCESS | 1642 | |2;audio-dft;181;185;185;185;182;183;185;184;182;184 15:45:07 INFO - PROCESS | 1642 | |3;audio-fft;81;59;60;88;62;59;58;59;84;58 15:45:07 INFO - PROCESS | 1642 | |4;audio-oscillator;83;70;94;82;95;100;124;80;69;94 15:45:07 INFO - PROCESS | 1642 | |5;imaging-gaussian-blur;87;84;86;83;86;84;87;84;87;83 15:45:07 INFO - PROCESS | 1642 | |6;imaging-darkroom;95;96;96;96;95;96;95;96;95;95 15:45:07 INFO - PROCESS | 1642 | |7;imaging-desaturate;82;81;80;82;81;84;85;84;81;81 15:45:07 INFO - PROCESS | 1642 | |8;json-parse-financial;52;54;53;54;56;59;59;57;57;57 15:45:07 INFO - PROCESS | 1642 | |9;json-stringify-tinderbox;71;66;73;76;71;69;68;71;69;71 15:45:07 INFO - PROCESS | 1642 | |10;stanford-crypto-aes;84;85;84;83;82;84;83;81;82;82 15:45:07 INFO - PROCESS | 1642 | |11;stanford-crypto-ccm;140;141;141;138;139;142;145;144;182;148 15:45:07 INFO - PROCESS | 1642 | |12;stanford-crypto-pbkdf2;164;213;167;166;166;164;163;163;164;172 15:45:07 INFO - PROCESS | 1642 | |13;stanford-crypto-sha256-iterative;69;71;84;86;89;84;73;68;67;66 15:45:07 INFO - PROCESS | 1642 | __end_tp_report 15:45:07 INFO - PROCESS | 1642 | __start_cc_report 15:45:07 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,1993 15:45:07 INFO - PROCESS | 1642 | __end_cc_report 15:45:07 INFO - PROCESS | 1642 | __startTimestamp1481931907327__endTimestamp 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | ------- Summary: start ------- 15:45:07 INFO - PROCESS | 1642 | Number of tests: 14 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | [#0] ai-astar Cycles:10 Average:113.10 Median:115.00 stddev:4.12 (3.6%) stddev-sans-first:4.24 15:45:07 INFO - PROCESS | 1642 | Values: 116.0 102.0 111.0 115.0 114.0 114.0 114.0 115.0 115.0 115.0 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | [#1] audio-beat-detection Cycles:10 Average:126.60 Median:126.50 stddev:21.80 (17.2%) stddev-sans-first:22.08 15:45:07 INFO - PROCESS | 1642 | Values: 145.0 114.0 139.0 173.0 114.0 111.0 111.0 109.0 141.0 109.0 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.50 stddev:1.51 (0.8%) stddev-sans-first:1.27 15:45:07 INFO - PROCESS | 1642 | Values: 181.0 185.0 185.0 185.0 182.0 183.0 185.0 184.0 182.0 184.0 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | [#3] audio-fft Cycles:10 Average:66.80 Median:61.00 stddev:12.26 (20.1%) stddev-sans-first:11.88 15:45:07 INFO - PROCESS | 1642 | Values: 81.0 59.0 60.0 88.0 62.0 59.0 58.0 59.0 84.0 58.0 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | [#4] audio-oscillator Cycles:10 Average:89.10 Median:82.50 stddev:16.19 (19.6%) stddev-sans-first:17.02 15:45:07 INFO - PROCESS | 1642 | Values: 83.0 70.0 94.0 82.0 95.0 100.0 124.0 80.0 69.0 94.0 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | [#5] imaging-gaussian-blur Cycles:10 Average:85.10 Median:86.00 stddev:1.66 (1.9%) stddev-sans-first:1.62 15:45:07 INFO - PROCESS | 1642 | Values: 87.0 84.0 86.0 83.0 86.0 84.0 87.0 84.0 87.0 83.0 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | [#6] imaging-darkroom Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 15:45:07 INFO - PROCESS | 1642 | Values: 95.0 96.0 96.0 96.0 95.0 96.0 95.0 96.0 95.0 95.0 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | [#7] imaging-desaturate Cycles:10 Average:82.10 Median:82.00 stddev:1.66 (2.0%) stddev-sans-first:1.76 15:45:07 INFO - PROCESS | 1642 | Values: 82.0 81.0 80.0 82.0 81.0 84.0 85.0 84.0 81.0 81.0 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:57.00 stddev:2.44 (4.3%) stddev-sans-first:2.17 15:45:07 INFO - PROCESS | 1642 | Values: 52.0 54.0 53.0 54.0 56.0 59.0 59.0 57.0 57.0 57.0 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:71.00 stddev:2.76 (3.9%) stddev-sans-first:2.92 15:45:07 INFO - PROCESS | 1642 | Values: 71.0 66.0 73.0 76.0 71.0 69.0 68.0 71.0 69.0 71.0 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.50 stddev:1.25 (1.5%) stddev-sans-first:1.27 15:45:07 INFO - PROCESS | 1642 | Values: 84.0 85.0 84.0 83.0 82.0 84.0 83.0 81.0 82.0 82.0 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:143.00 stddev:13.00 (9.1%) stddev-sans-first:13.60 15:45:07 INFO - PROCESS | 1642 | Values: 140.0 141.0 141.0 138.0 139.0 142.0 145.0 144.0 182.0 148.0 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.20 Median:166.00 stddev:15.27 (9.2%) stddev-sans-first:16.03 15:45:07 INFO - PROCESS | 1642 | Values: 164.0 213.0 167.0 166.0 166.0 164.0 163.0 163.0 164.0 172.0 15:45:07 INFO - PROCESS | 1642 | 15:45:07 INFO - PROCESS | 1642 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.70 Median:78.50 stddev:8.97 (11.4%) stddev-sans-first:9.18 15:45:07 INFO - PROCESS | 1642 | Values: 69.0 71.0 84.0 86.0 89.0 84.0 73.0 68.0 67.0 66.0 15:45:07 INFO - PROCESS | 1642 | -------- Summary: end -------- 15:45:07 INFO - PROCESS | 1642 | 15:45:08 INFO - TEST-INFO | 1642: exit 0 15:45:08 INFO - TEST-OK | kraken | took 59649ms 15:45:08 INFO - SUITE-END | took 1181s 15:45:08 INFO - Completed test suite (00:19:41) 15:45:08 INFO - javascript benchmark 15:45:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2795.510785612518, 3336.549565808615, 3345.882894098707, 3363.0247228303792, 3367.882124125656, 5598.468145526173, 5634.915476267859, 5675.318961724599, 5724.284464441945, 5731.025725626649, 5616.803411880585, 5707.115397113444, 5725.4847063764255, 5748.913766293509, 5768.653880767157, 5175.715335656547, 5219.00838840621, 5240.737963101848, 5243.161094224924, 5297.576193904479, 5120.129577971735, 5136.024155410479, 5136.5890728741615, 5197.7920883164625, 5322.733863306838, 5221.190715439113, 5221.477852214767, 5230.398504172012, 5230.92153617696, 5231.790728370861, 5214.374275086995, 5239.502247286521, 5239.685618862843, 5240.9213861792105, 5243.737813109367, 5054.039732450822, 5164.948350516484, 5169.147090730025, 5180.792768289264, 5225.712585807779, 5734.4265573442735, 5742.138679198112, 5751.884962300731, 5763.337216220105, 5766.0486019806685, 6094.748796175517, 6124.208312778138, 6128.411459112332, 6130.092410195535, 6136.286473208055, 6210.72051757672, 6229.37706229378, 6254.655993920333, 6274.592151510183, 6282.842928926768, 5740.167675687044, 5747.3677895431465, 5747.56893233005, 5756.1653560233535, 5785.623934444247, 5299.5760339172775, 6208.286047104641, 6278.30938596754, 6341.968290158566, 6354.17395738551, 6012.909806352908, 6019.187409699634, 6025.879482410327, 6062.818115456544, 6063.454289114001, 6227.19046523949, 6230.968845155745, 6240.5319601030105, 6253.062040693932, 6271.843203919939, 6022.2171117754415, 6225.688715564294, 6225.8132256032395, 6229.470495007885, 6245.063240513869, 5810.82567522975, 6079.148919151322, 6124.991279909952, 6135.53983451234, 6144.938550614526, 4366.585174408426, 4488.842890498817, 4604.1252162089095, 4863.124637565202, 4864.0, 4378.6278166356215, 4409.11817636474, 4414.984553552701, 4422.358757980075, 4437.534058923767, 5556.77772889088, 5609.747561359749, 5626.324841019104, 5633.464820842013, 5676.517496012885, 6113.506711826627, 6160.44555989963, 6184.474319682788, 6202.906956395657, 6251.187345645037, 5758.74085666146, 5768.826935191951, 5770.538356931435, 5774.105013722879, 5775.711214439345, 5284.154535274338, 5320.574354051667, 5355.919661205007, 5373.462653734673, 5399.136138217867, 6210.5652604317265, 6213.285472170577, 6224.595401298901, 6283.497320214463, 6288.905666415099, 5166.4316925137455, 6310.936890631218, 6312.053192021233, 6312.747490100344, 6326.398992095744, 6234.878556489008, 6272.153259309935, 6277.5605707600025, 6298.433141017147, 6306.369363063657, 5946.137810017437, 6059.878802424015, 6152.815415537541, 6156.984097623842, 6161.075838624242, 6142.744345496893, 6159.538034647329, 6160.876782464416, 6235.46998505114, 6278.995360742259, 5379.5965302603245, 5648.915266271091, 6066.939330606814, 6080.847978800565, 6091.543134264859, 6017.759289628366, 6019.337872834072, 6046.606970546901, 6087.69561521931, 6092.390760923872, 5672.2058911751565, 5696.629719068247, 5824.271966004249, 5826.621269617462, 5913.586048976703, 6089.482393996383, 6101.938980610137, 6112.169143789106, 6130.2030736004035, 6136.110264011782, 5706.686132262766, 5761.164631128547, 5766.365699773106, 5780.248567686174, 5863.618864773776], "value": 5642.476810860733, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.9178435558133, 513.3480479790666, 516.4396629656821, 527.6359312074666, 553.5405613340928, 29558.556621650692, 38760.0, 39712.01439928003, 39716.60283397165, 41081.58918410815, 40724.0, 40940.59059409405, 41072.0, 41082.0, 41106.794466027626, 8262.83474330513, 9171.312151588625, 9321.906780932171, 9339.719808405725, 9345.626174952993, 8253.628586713583, 10916.01755841974, 11248.043916267086, 11260.099192064656, 11376.317420954727, 11179.608713695023, 11207.66377008691, 11212.0, 11246.718832029183, 11251.493682784294, 11010.284331518465, 11061.778764424707, 11093.72265693356, 11095.1123910087, 11107.278026928305, 11085.556577736961, 11131.276467029698, 11190.888091119148, 11219.887801121966, 11242.213045086766, 38357.0, 38515.42226866599, 38597.61402385996, 38629.61370386288, 38736.45054197829, 3245.334706385185, 3305.074579117851, 3399.558057452515, 3671.81640917953, 3830.540335159799, 27538.724612754017, 27569.862150689118, 27685.446291074266, 28431.715682843114, 29127.126186214235, 3004.5410556093248, 3261.0, 3315.6850099240532, 3330.733541316689, 3336.4995250712345, 437735.0, 438532.0, 438626.0, 441157.9999999968, 442463.0, 46912.530874691496, 56595.717021415454, 56986.7150664244, 56987.430125699044, 57023.0, 59838.70080649656, 59871.700641496514, 59964.70017649884, 59996.40003599908, 60009.399906000384, 12748.171368860994, 12988.480460781651, 12993.415296311689, 12998.610041698765, 13014.21914685122, 9087.136722011399, 9289.396189247676, 9342.48616326095, 9905.851412228822, 9925.2059835213, 16992.511215821043, 20110.0, 20163.89098599562, 20442.795572044386, 20686.482837929172, 7946.960265198637, 7978.960105199553, 8029.036515618163, 8126.75619731409, 8128.9593552031865, 850.459957926721, 964.9855252171224, 1014.5688082564881, 1020.8315627921459, 1039.7920415916847, 29018.0, 29163.0, 29428.264293392836, 29950.700492995227, 30136.84931575328, 973.5764942250144, 1005.1205195453978, 1027.3527677563088, 1064.9882611519088, 1079.8542196803487, 14595.489157879423, 15179.316930738143, 15180.696386072437, 15377.550612945715, 15666.921665391601, 625474.0, 634150.0, 634806.0, 635490.0, 635923.0, 56060.719696401255, 56391.71804140953, 56472.43527564754, 56603.15095273574, 56757.14864277039, 1193.021722187815, 1241.1064033895761, 1314.6319030671427, 1332.3737843213673, 1348.885344745688, 23885.402864928517, 29194.56208156837, 29837.850810745807, 30410.847945760128, 30683.846580766953, 47121.057578848915, 52626.0, 54335.72832135814, 54473.455265446835, 57330.85338293294, 606743.9325606688, 607869.0, 613868.0, 615298.0, 616160.0, 21790.78209217951, 25637.743622563536, 26352.34119147037, 26627.201183964513, 41856.79071604623, 44894.551054490345, 45031.77484112558, 45505.54494455013, 45803.854903627675, 45813.541864580926, 36372.454413183295, 36609.25833748133, 37387.43918841274, 38240.80879595584, 38776.2244755109, 29832.403351932404, 36693.0, 36844.63155368412, 36865.815670921475, 36929.63070369369, 36303.636963631085, 36607.45088823723, 36990.815045924595, 37094.629053710196, 37405.812970934974, 29791.0, 31609.683903160672, 32040.0, 32992.67007329992, 33679.83160084184, 24649.383765406008, 24690.0, 24865.62701559442, 25422.618660719734, 25481.235562933143], "value": 19661.706079769498, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.61453749993757, 353.33572882979996, 372.4171671334362, 380.5851621732313, 405.24219709143904, 14296.28518574071, 14718.63203419915, 15759.81801364898, 15839.287232074556, 20205.595888082265, 18357.90821045896, 19122.61754764904, 19881.602367952633, 20062.097205625745, 20078.598428031466, 5492.972535137319, 5681.687507187103, 5687.744051517671, 5788.942110578903, 5816.82549523515, 4633.9073218535605, 5641.210230567704, 5720.628159169662, 5760.654360738348, 5781.103928891029, 5717.428257174291, 5720.771169153229, 5724.4561766632105, 5725.971370143144, 5744.310682718059, 5644.604877658545, 5726.942730572682, 5937.821865344046, 5970.432808883149, 6032.3967603239325, 5642.689652069134, 5812.709364531756, 5826.766929322822, 5828.125781132864, 5849.532037436995, 13040.934795325962, 13327.933360333136, 13393.464261429532, 13407.396667150002, 13422.93288533561, 2098.95802083959, 2219.1345375303645, 2257.3679369776487, 2302.2057390200353, 2309.0879102754484, 9814.75463113428, 9918.553665085017, 10171.033751793568, 10350.534225959775, 10459.0, 1804.0, 1819.9171492961798, 1862.3388697012501, 1888.4051523769967, 1914.5692219250695, 111613.0, 119041.0, 119122.0, 121823.0, 128569.35715321364, 7645.847083058307, 9741.220702343795, 9995.900040999642, 10067.496625168713, 11213.383263920563, 12411.255324680547, 12499.000079993579, 12502.687432814253, 12540.686482838002, 12548.372581370895, 6289.7169627366875, 6811.739828131812, 7032.613206273704, 7117.0, 7171.964140179266, 7004.684789184499, 7042.225355210922, 7097.467689923174, 7162.319579639926, 7215.134183897966, 3774.7923864187733, 3893.3186692328727, 3903.6291552302487, 3917.236138952877, 3937.547182074041, 1609.798775153106, 1863.1429542410642, 1895.090356628826, 1899.917047283063, 1924.7594050743658, 837.1419295222446, 843.472829481574, 865.5888452984782, 911.0889110889111, 913.8492148795507, 19378.515537111685, 20241.392758217276, 20284.695729564068, 20317.968203179564, 20756.68864967027, 799.836238273311, 804.481109684262, 805.1988271669726, 839.7606682095574, 925.666759966425, 10436.530356133992, 10738.8187299397, 10751.408672523086, 10777.83833242502, 10787.514561844579, 601877.0, 606970.0, 615507.0, 632080.0, 633691.0, 122430.57251204737, 128102.35948820197, 129910.0, 138154.92767608495, 138982.52543686487, 947.2516711797603, 949.7008442340641, 956.086936975201, 966.6858271061933, 971.0483725948469, 16958.0, 17474.47576572705, 18134.90932545329, 18157.36449224271, 18559.16483758207, 47509.0, 49248.0, 53497.19754203612, 56407.71796140993, 56530.43469565252, 70777.6461117691, 72505.91241131493, 75329.6233518829, 78066.0, 78528.0, 7847.269510067647, 7967.840643186988, 8081.15147909461, 8223.79440513968, 8374.53940033304, 9250.537473126182, 12350.814737779121, 12693.682657933625, 12880.613581592566, 13261.939044876579, 11059.281146725438, 11061.834072488758, 11138.88861111411, 11473.368964706688, 11516.654500365003, 9070.546472676471, 10971.396573188233, 11105.666829995112, 11113.833292500458, 11324.773504530029, 11378.886211137782, 11403.429828508708, 11563.6530904073, 11642.41787910618, 11663.65009049696, 7973.481723687942, 8025.398095142771, 8054.637541310773, 8228.629711663109, 8389.203025712548, 7598.620068996639, 9775.902240977499, 10982.725431864268, 11280.153988451064, 11319.490622922152], "value": 8970.986869575516, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1282.608804314685, 1337.8729020743028, 1404.6699025728951, 1522.9923850380746, 1788.651213013463, 7415.740449084283, 7754.806129846756, 8071.677132914677, 8318.0, 8345.833083338344, 8158.0, 8274.91725082749, 8278.54468004261, 8279.089300176976, 8279.337652987762, 2301.7583153768865, 2327.1156960355042, 2331.673565700795, 2332.486852892361, 2334.0663734506247, 1990.5222746540871, 2316.6872472216287, 2357.1867705641525, 2376.2039716694926, 2379.7858192762646, 2307.4231442139467, 2316.7683231676865, 2343.4375749820006, 2349.1425629645228, 2354.4702441950594, 2292.266474728088, 2308.3998160478313, 2326.220716060122, 2331.3122628824535, 2336.3224664847176, 2331.8717470539113, 2356.1282325539487, 2357.233898982837, 2359.5162991586685, 2359.787619114271, 7505.62471876404, 7803.960980195063, 8288.461250018729, 8306.667733290662, 8309.45988510751, 5011.523905228997, 5212.1139406300645, 5220.451852555503, 5258.974499972507, 5261.5264626183825, 3363.1928337198897, 3498.4227602445567, 3526.806025668587, 3573.1781690211396, 3580.4987301777774, 1053.3311347294523, 1071.9035286824146, 1084.8589683341115, 1090.43297485308, 1098.6978580890238, 10796.5681372745, 10849.457527123612, 10946.945265273622, 10982.341059536453, 10993.61522346722, 4273.145370925765, 4398.538153493901, 4509.594136527729, 4513.909721805546, 4517.390152329459, 8697.521636310064, 8700.52147131901, 8707.216350528483, 8716.825663486821, 8732.214100730966, 5135.820246291437, 5139.203423469368, 5145.716985565755, 5148.124818780741, 5150.639455238097, 4062.979685101556, 4315.417418648505, 4434.26834572292, 4457.888552786207, 4841.806327746851, 3591.892243232655, 3624.7281453891064, 3725.5343082114737, 3737.2712321097124, 3744.981275093607, 1138.599522587206, 1140.560884059643, 1142.4402042999036, 1158.1892675127444, 1163.2787671643466, 803.1687203744096, 880.7489865388334, 894.1952242981368, 909.3043821476575, 914.1178762494092, 5428.972855135699, 5457.945420545823, 5541.33503979517, 5659.773609055592, 5737.655740655574, 917.8944421391492, 954.9474778887228, 974.0503009565617, 985.9852102218473, 1030.2376241581176, 3676.8713095041544, 3756.6619004289746, 3770.1517158639085, 3846.3268927937497, 3871.496705428276, 9803.705888823346, 9979.950100249453, 10010.699679009642, 10270.024347686958, 10285.794284114276, 10001.34991225568, 10029.548670309854, 10245.129164020993, 10275.897241027644, 10276.743081423025, 7514.248575142551, 7735.110462296908, 7902.802429939183, 7916.920830791619, 7929.643166057426, 5302.628815982998, 5427.294451721251, 5567.192757050124, 5687.971560142173, 5721.771129154788, 9024.954875225581, 9063.32025098107, 9360.0, 9434.622615095594, 9476.147146756824, 8585.656573737231, 8908.510031948304, 9049.864252036094, 9288.024757400375, 9399.248060155307, 5994.430529099728, 7766.417518686008, 7781.06627204739, 7793.961030194812, 7852.13626501073, 7817.609119543887, 7938.364930805637, 7994.200579941959, 8010.279074883289, 8018.398620103399, 6995.545289556163, 7043.894341584983, 7064.222935476991, 7107.324804143598, 7107.644617768988, 6855.314468553105, 6890.724371025103, 6901.689423976033, 6903.482238832007, 6973.302669733189, 6592.0, 6595.571287866273, 6598.967005164944, 6672.266050734513, 6679.265280819203, 4599.954000460087, 4655.23188673865, 4670.556297151765, 4739.1706451371565, 4767.427908650984, 4746.928796067993, 4764.523547645346, 4806.2310030394965, 4868.318435418973, 4925.38432695913], "value": 4378.177482322902, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.39831142780156, 307.07417137330947, 308.32015406029706, 313.83723305154393, 318.69086985623966, 4487.977560112199, 4714.080754252922, 5049.4698056704065, 5096.668716533427, 5128.53836656553, 4641.443026836776, 4654.672663668166, 4706.90586188276, 4970.887372866536, 4998.775055122519, 3341.74936879735, 3474.4462819827468, 3673.338799016176, 3827.5789663137034, 3870.612938706135, 3570.1967057412135, 3750.887473375803, 3855.5541671873048, 3909.257241124177, 3917.8628747993826, 3215.099772063826, 3785.432185172219, 3796.9240615187678, 3839.7696138231654, 3841.3661745811905, 3602.4776407420777, 3670.596234414212, 3678.5401824771902, 3709.99450334503, 3857.0, 3378.831058447068, 3378.9557317822855, 3384.4415671414185, 3411.4371128763723, 3422.0, 4029.1135950090934, 4339.0, 4827.565519103263, 4867.537583929557, 4875.317455556225, 4181.540030596632, 4394.560543945612, 4400.603945644875, 4405.22908491016, 4417.271150260203, 4920.843601753585, 4934.333864928225, 5253.711045892474, 5318.0, 5352.357717073937, 3573.8391772370305, 3744.2324323513612, 3747.2880152770886, 3748.775073495599, 3775.565809931838, 4663.510331415187, 4875.804967801319, 5544.84136897327, 5579.330480342344, 5619.466150715716, 3358.596968363812, 3686.6681998619715, 3706.4440333950124, 3721.9441708374397, 3728.4780130781714, 3708.6847617952235, 3761.0, 3780.3006443807985, 3796.4115562087923, 3802.7338086334244, 3660.8352624131444, 3683.7605555638797, 4365.236083685342, 4378.2119218540965, 4386.342048692722, 4534.841280555164, 4975.328330675385, 5395.271638328775, 5402.648827826179, 5404.6218214355085, 1080.303204433152, 1148.6445993727482, 1217.1905682720892, 1223.969400764988, 1232.2421710648066, 804.3283857978537, 825.2490233887133, 829.3199576347458, 834.9123342049119, 839.0812060793421, 4118.951975538081, 4204.285271503852, 4366.388705581221, 4427.380166776654, 4503.617192538671, 4829.348038014893, 4943.220440641367, 5444.6188766785945, 5476.7535460903555, 5491.780328786884, 4448.822047118144, 5017.3226614406585, 5021.347224860745, 5023.020511000393, 5032.458608402733, 2285.851419657717, 2313.61183290028, 2587.0, 2648.854313012803, 2737.6988531261545, 4572.337011133433, 4694.389729335165, 5308.849915784113, 5317.3147560482485, 5337.306150200449, 4784.760761961888, 5275.28553220205, 5282.603804714662, 5291.920621190685, 5364.624476286701, 4559.3844830948065, 4931.9753401232765, 4949.752512374295, 5134.5378915897, 5339.7063161526485, 4281.486221653421, 4871.171900776947, 4890.266460031024, 5061.544460998528, 5148.871278217925, 4656.8137274508645, 4814.783334750015, 5035.370578677665, 5074.720890351066, 5105.974470127626, 5247.895042099213, 5662.518685911579, 5703.973284808609, 5710.486056254957, 5732.570057245806, 3334.0831271400557, 3680.8343624536424, 3697.0572504011307, 3717.591064982904, 3750.7937063461773, 5046.6719663221775, 5324.920126198032, 5530.101278598659, 5535.916961245504, 5582.246396736551, 5164.302819119371, 5320.0, 5339.279197308314, 5349.893002140013, 5380.0, 5192.2605927013365, 5316.175992721134, 5328.25404443386, 5341.41244463101, 5361.490658387447, 4569.81720731167, 4948.035133149001, 5200.63595548308, 5241.139395514538, 5250.684958902478, 4369.951211709231, 4529.977350113229, 4798.904021919472, 5220.895582088413, 5306.018386598492, 4659.555537783298, 4933.457319694902, 5075.720835354091, 5106.821261255838, 5225.947740522546], "value": 3817.9322064063617, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.911091558097, 688.1329524798753, 700.4676445901115, 753.600591686406, 792.7185849023595, 13824.930875345623, 14334.498292559763, 14342.784858227134, 14378.640533986629, 14385.712285754278, 12427.440765165567, 14030.859691403082, 14658.97387182895, 14683.192424416653, 14972.401103955828, 651.3161180760206, 675.3921470676406, 813.1299509524811, 838.714836955435, 839.7774589733725, 679.2765704524691, 801.9759407217764, 818.8812622169781, 833.8832563441093, 839.0518713853337, 871.477113731759, 874.204473928725, 874.7769318823691, 875.8117004843957, 879.3668558637803, 689.1592257445908, 714.4927101757759, 863.2619110660421, 865.0916537635458, 866.3805379153904, 635.1171871099176, 654.7839213059726, 659.1003280522101, 661.4377778887955, 663.1081195791701, 14224.35990380425, 14272.0, 14277.14337139775, 14283.000189986706, 14296.142231466041, 12682.175658582162, 12813.35933203336, 12942.352882355939, 12955.546555870593, 12972.156809807333, 11859.881401185963, 12064.091417215253, 12120.151589388746, 12210.450529726184, 12650.114491985565, 4304.935425968613, 4324.740515569076, 4425.424694789689, 4494.842680506199, 4511.548845115495, 82088.58955705183, 82132.3573528532, 82247.5887620564, 82807.7578836318, 83574.0, 9689.356848643072, 9792.167665748491, 10212.744681382876, 10229.948850255701, 10257.17942564593, 18051.00719460416, 18704.438866833745, 18708.719369209473, 18725.81274187268, 18736.437906862815, 7270.63646817657, 7271.272872712792, 7284.599347035964, 7358.889616655754, 7482.027336446227, 3953.6441720245316, 4027.9597204027586, 4174.332106862948, 4226.936595951063, 4260.382244574581, 2872.6785678587667, 3186.2193762528327, 3292.489664102068, 3296.7197788188273, 3303.702666760003, 614.5483069943588, 630.8265227062503, 634.1407392982535, 646.6928209100639, 649.870025994803, 543.8939406815633, 549.9367572729108, 550.5292974506739, 593.4006653280131, 608.7504123309421, 7258.745943891939, 8219.54792486407, 8526.829463410697, 8626.698065567674, 8999.505027223566, 602.4668168670757, 609.6951524237882, 618.4588485075559, 624.9375062493714, 642.1331202876173, 4027.7381970171846, 4247.426597409372, 4286.421333120051, 4334.605680274301, 4368.082702632482, 89199.0, 93731.53134234421, 96230.0, 100462.49768751109, 106434.0, 59432.0, 63436.36563634305, 77842.2215777835, 83404.58297708472, 84172.15827841766, 80179.19820801834, 80498.59750701209, 80921.59539202266, 82352.0, 84286.57856710923, 9122.452216670621, 9426.481543515172, 9622.473750986614, 9739.561719722771, 9759.165439616605, 47468.52531474641, 51182.720431989495, 51491.48508515017, 52699.0, 52874.20688689596, 51514.0, 53416.46583534115, 72363.27636723565, 72631.0, 74397.62801185959, 12255.0, 12348.814767778671, 12407.205212960493, 12587.622371328875, 12608.87391126077, 13806.102603330752, 14316.06945548536, 14404.351804168627, 14457.0, 14519.92740036293, 10847.511861966073, 12841.101122921305, 13201.60395188146, 13304.334783260992, 13394.464221431033, 12456.937715311366, 12472.438740256892, 12669.239845609292, 12855.742885142432, 13319.134256273312, 12891.290978996245, 12915.935420322838, 12921.741565168455, 12925.418356174183, 13036.739265214832, 10652.786944261226, 10851.891481085404, 11136.99767020972, 11158.0, 11456.82814757796, 11199.776004479701, 11373.374464404536, 11405.771884562097, 11517.82723259135, 11678.474468648761], "value": 7088.6150510572015, "unit": "score"}], "name": "dromaeo_css", "value": 7002.889764218332, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 102.0, 111.0, 115.0, 114.0, 114.0, 114.0, 115.0, 115.0, 115.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 114.0, 139.0, 173.0, 114.0, 111.0, 111.0, 109.0, 141.0, 109.0], "value": 126.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 185.0, 185.0, 182.0, 183.0, 185.0, 184.0, 182.0, 184.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 88.0, 62.0, 59.0, 58.0, 59.0, 84.0, 58.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 94.0, 82.0, 95.0, 100.0, 124.0, 80.0, 69.0, 94.0], "value": 89.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 83.0, 86.0, 84.0, 87.0, 84.0, 87.0, 83.0], "value": 85.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 80.0, 82.0, 81.0, 84.0, 85.0, 84.0, 81.0, 81.0], "value": 82.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 53.0, 54.0, 56.0, 59.0, 59.0, 57.0, 57.0, 57.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 66.0, 73.0, 76.0, 71.0, 69.0, 68.0, 71.0, 69.0, 71.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 83.0, 82.0, 84.0, 83.0, 81.0, 82.0, 82.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 141.0, 138.0, 139.0, 142.0, 145.0, 144.0, 182.0, 148.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 213.0, 167.0, 166.0, 166.0, 164.0, 163.0, 163.0, 164.0, 172.0], "value": 170.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 84.0, 86.0, 89.0, 84.0, 73.0, 68.0, 67.0, 66.0], "value": 75.7, "unit": "score"}], "name": "kraken", "value": 1443.1, "alertThreshold": 2.0}]} 15:45:08 INFO - Return code: 0 15:45:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:45:08 INFO - # TBPL SUCCESS # 15:45:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:45:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:45:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:45:08 INFO - 2016-12-16 15:45:08.683 screenresolution[1646:9809] starting screenresolution argv=screenresolution get 15:45:08 INFO - 2016-12-16 15:45:08.686 screenresolution[1646:9809] Display 0: 1600x1200x32@60 15:45:08 INFO - 2016-12-16 15:45:08.692 screenresolution[1647:9812] starting screenresolution argv=screenresolution list 15:45:08 INFO - Available Modes on Display 0 15:45:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:45:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:45:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:45:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:45:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:45:08 INFO - Intel Iris: 15:45:08 INFO - Chipset Model: Intel Iris 15:45:08 INFO - Type: GPU 15:45:08 INFO - Bus: Built-In 15:45:08 INFO - VRAM (Dynamic, Max): 1536 MB 15:45:08 INFO - Vendor: Intel (0x8086) 15:45:08 INFO - Device ID: 0x0a2e 15:45:08 INFO - Revision ID: 0x0009 15:45:08 INFO - Displays: 15:45:08 INFO - Display: 15:45:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:45:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:45:08 INFO - Display Serial Number: 1600x1200 60 15:45:08 INFO - Main Display: Yes 15:45:08 INFO - Mirror: Off 15:45:08 INFO - Online: Yes 15:45:08 INFO - Rotation: Supported 15:45:08 INFO - Return code: 0 15:45:08 INFO - Running post-action listener: _resource_record_post_action 15:45:08 INFO - [mozharness: 2016-12-16 23:45:08.939970Z] Finished run-tests step (success) 15:45:08 INFO - Running post-run listener: _resource_record_post_run 15:45:09 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 33446912; Write bytes: 655925760; Read time: 317; Write time: 1832 15:45:09 INFO - TinderboxPrint: CPU usage
53.5% 15:45:09 INFO - TinderboxPrint: I/O read bytes / time
33,446,912 / 317 15:45:09 INFO - TinderboxPrint: I/O write bytes / time
655,925,760 / 1,832 15:45:09 INFO - TinderboxPrint: CPU idle
2,258.0 (46.5%) 15:45:09 INFO - TinderboxPrint: CPU system
969.1 (20.0%) 15:45:09 INFO - TinderboxPrint: CPU user
1,626.7 (33.5%) 15:45:09 INFO - TinderboxPrint: Swap in / out
377,544,704 / 0 15:45:09 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349651456; Write bytes: 350467584; Read time: 26850; Write time: 664 15:45:09 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 29228032; Write bytes: 304118784; Read time: 283; Write time: 1163 15:45:09 INFO - Running post-run listener: _upload_blobber_files 15:45:09 INFO - Blob upload gear active. 15:45:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:45:09 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 15:45:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:45:09 INFO - (blobuploader) - INFO - Open directory for files ... 15:45:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:45:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:45:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:45:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:45:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:45:11 INFO - (blobuploader) - INFO - Done attempting. 15:45:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:45:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:45:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:45:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:45:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:45:12 INFO - (blobuploader) - INFO - Done attempting. 15:45:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:45:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:45:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:45:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:45:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:45:14 INFO - (blobuploader) - INFO - Done attempting. 15:45:14 INFO - (blobuploader) - INFO - Iteration through files over. 15:45:14 INFO - Return code: 0 15:45:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:45:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:45:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c95f50ef013c01b79a6c2b9ef1758719d8dd814782a8b8051574a05df6af564054117464de60e51fbb78f02801ea6e715144ac8f2a0fbd7b41510c92ff028f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a0266aa27597eeb7ef01076b20912d6a82051aedcb2890c13564c4756d3833ef8649e8c2f1b321dce1ed81462e6b0901e00465be9330ec2c79a2c758d6487a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08dc19c316c58092760b1f7b22a2d08544887cbf817f86d0ed67cc58d4f6de1b721c3029a92758335c3ac04b9f8582bbeace29a1be95cd22350e9e911489b01d"} 15:45:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:45:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:45:14 INFO - Contents: 15:45:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c95f50ef013c01b79a6c2b9ef1758719d8dd814782a8b8051574a05df6af564054117464de60e51fbb78f02801ea6e715144ac8f2a0fbd7b41510c92ff028f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a0266aa27597eeb7ef01076b20912d6a82051aedcb2890c13564c4756d3833ef8649e8c2f1b321dce1ed81462e6b0901e00465be9330ec2c79a2c758d6487a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08dc19c316c58092760b1f7b22a2d08544887cbf817f86d0ed67cc58d4f6de1b721c3029a92758335c3ac04b9f8582bbeace29a1be95cd22350e9e911489b01d"} 15:45:14 INFO - Running post-run listener: copy_logs_to_upload_dir 15:45:14 INFO - Copying logs to upload dir... 15:45:14 INFO - mkdir: /builds/slave/test/build/upload/logs 15:45:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.264739 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-16 15:45:14.464270) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 15:45:14.467934) ========= 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.wgzk4B55Cz/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.yYWre2WoGg/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c95f50ef013c01b79a6c2b9ef1758719d8dd814782a8b8051574a05df6af564054117464de60e51fbb78f02801ea6e715144ac8f2a0fbd7b41510c92ff028f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a0266aa27597eeb7ef01076b20912d6a82051aedcb2890c13564c4756d3833ef8649e8c2f1b321dce1ed81462e6b0901e00465be9330ec2c79a2c758d6487a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08dc19c316c58092760b1f7b22a2d08544887cbf817f86d0ed67cc58d4f6de1b721c3029a92758335c3ac04b9f8582bbeace29a1be95cd22350e9e911489b01d"} build_url:https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010946 build_url: 'https://queue.taskcluster.net/v1/task/S9p35ZbRQqC75c0l-vD90w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c95f50ef013c01b79a6c2b9ef1758719d8dd814782a8b8051574a05df6af564054117464de60e51fbb78f02801ea6e715144ac8f2a0fbd7b41510c92ff028f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a0266aa27597eeb7ef01076b20912d6a82051aedcb2890c13564c4756d3833ef8649e8c2f1b321dce1ed81462e6b0901e00465be9330ec2c79a2c758d6487a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08dc19c316c58092760b1f7b22a2d08544887cbf817f86d0ed67cc58d4f6de1b721c3029a92758335c3ac04b9f8582bbeace29a1be95cd22350e9e911489b01d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 15:45:14.502640) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 15:45:14.502997) ========= 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.wgzk4B55Cz/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.yYWre2WoGg/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.005229 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 15:45:14.551507) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 15:45:14.551830) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 15:45:14.552277) ========= ========= Total master_lag: 0.18 =========