builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0269 starttime: 1476385953.77 results: success (0) buildid: 20161013094723 builduid: 0da20c8661f44af2817d36ba298c6937 revision: 7452437b3ab571b1d60aed4e973d82a1471f72b2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 12:12:33.767546) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 12:12:33.768042) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 12:12:33.768316) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 12:12:33.793033) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:12:33.793416) ========= 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.gNkrkN0xoE/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.opYDuCxEK6/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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-10-13 12:12:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 190M=0s 2016-10-13 12:12:33 (190 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.079940 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:12:33.895872) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:12:33.896185) ========= 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.gNkrkN0xoE/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.opYDuCxEK6/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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.025842 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:12:33.944713) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:12:33.945071) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7452437b3ab571b1d60aed4e973d82a1471f72b2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 7452437b3ab571b1d60aed4e973d82a1471f72b2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gNkrkN0xoE/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.opYDuCxEK6/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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-10-13 12:12:34,027 truncating revision to first 12 chars 2016-10-13 12:12:34,027 Setting DEBUG logging. 2016-10-13 12:12:34,027 attempt 1/10 2016-10-13 12:12:34,027 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7452437b3ab5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 12:12:34,719 unpacking tar archive at: mozilla-central-7452437b3ab5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.965371 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:12:34.934083) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 12:12:34.934630) ========= script_repo_revision: 7452437b3ab571b1d60aed4e973d82a1471f72b2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 12:12:34.935293) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 12:12:34.935758) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 12:12:34.954672) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-13 12:12:34.955080) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gNkrkN0xoE/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.opYDuCxEK6/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 12:12:35 INFO - MultiFileLogger online at 20161013 12:12:35 in /builds/slave/test 12:12:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:12:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:12:35 INFO - {'append_to_log': False, 12:12:35 INFO - 'base_work_dir': '/builds/slave/test', 12:12:35 INFO - 'blob_upload_branch': 'Firefox', 12:12:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:12:35 INFO - 'branch': 'Firefox', 12:12:35 INFO - 'buildbot_json_path': 'buildprops.json', 12:12:35 INFO - 'config_files': ('talos/mac_config.py',), 12:12:35 INFO - 'default_actions': ('clobber', 12:12:35 INFO - 'read-buildbot-config', 12:12:35 INFO - 'download-and-extract', 12:12:35 INFO - 'populate-webroot', 12:12:35 INFO - 'create-virtualenv', 12:12:35 INFO - 'install', 12:12:35 INFO - 'run-tests'), 12:12:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:12:35 INFO - 'download_minidump_stackwalk': True, 12:12:35 INFO - 'download_symbols': 'ondemand', 12:12:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:12:35 INFO - 'tooltool.py': '/tools/tooltool.py', 12:12:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:12:35 INFO - '/tools/misc-python/virtualenv.py')}, 12:12:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:12:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:12:35 INFO - 'installer_path': 'installer.exe', 12:12:35 INFO - 'log_level': 'info', 12:12:35 INFO - 'log_name': 'talos', 12:12:35 INFO - 'log_to_console': True, 12:12:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:12:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:12:35 INFO - 'opt_config_files': (), 12:12:35 INFO - 'pip_index': False, 12:12:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:12:35 INFO - 'cmd': ('bash', 12:12:35 INFO - '-c', 12:12:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:12:35 INFO - 'enabled': True, 12:12:35 INFO - 'halt_on_failure': False, 12:12:35 INFO - 'name': 'check_screen_resolution'},), 12:12:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:12:35 INFO - 'cmd': ('bash', 12:12:35 INFO - '-c', 12:12:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:12:35 INFO - 'enabled': True, 12:12:35 INFO - 'halt_on_failure': False, 12:12:35 INFO - 'name': 'check_screen_resolution'},), 12:12:35 INFO - 'run_cmd_checks_enabled': True, 12:12:35 INFO - 'sps_profile': False, 12:12:35 INFO - 'sps_profile_interval': 0, 12:12:35 INFO - 'suite': 'dromaeojs', 12:12:35 INFO - 'system_bits': '32', 12:12:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:12:35 INFO - 'title': 't-yosemite-r7-0269', 12:12:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:12:35 INFO - 'use_talos_json': True, 12:12:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:12:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:12:35 INFO - 'work_dir': 'build'} 12:12:35 INFO - [mozharness: 2016-10-13 19:12:35.156596Z] Running clobber step. 12:12:35 INFO - Running pre-action listener: _resource_record_pre_action 12:12:35 INFO - Running main action method: clobber 12:12:35 INFO - rmtree: /builds/slave/test/build 12:12:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:12:37 INFO - Running post-action listener: _resource_record_post_action 12:12:37 INFO - [mozharness: 2016-10-13 19:12:37.159350Z] Finished clobber step (success) 12:12:37 INFO - [mozharness: 2016-10-13 19:12:37.159476Z] Running read-buildbot-config step. 12:12:37 INFO - Running pre-action listener: _resource_record_pre_action 12:12:37 INFO - Running main action method: read_buildbot_config 12:12:37 INFO - Using buildbot properties: 12:12:37 INFO - { 12:12:37 INFO - "project": "", 12:12:37 INFO - "product": "firefox", 12:12:37 INFO - "script_repo_revision": "production", 12:12:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 12:12:37 INFO - "repository": "", 12:12:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 12:12:37 INFO - "buildid": "20161013094723", 12:12:37 INFO - "pgo_build": "False", 12:12:37 INFO - "basedir": "/builds/slave/test", 12:12:37 INFO - "buildnumber": 49, 12:12:37 INFO - "slavename": "t-yosemite-r7-0269", 12:12:37 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 12:12:37 INFO - "platform": "yosemite_r7", 12:12:37 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:12:37 INFO - "branch": "mozilla-central", 12:12:37 INFO - "repo_path": "mozilla-central", 12:12:37 INFO - "revision": "7452437b3ab571b1d60aed4e973d82a1471f72b2", 12:12:37 INFO - "stage_platform": "macosx64", 12:12:37 INFO - "builduid": "0da20c8661f44af2817d36ba298c6937", 12:12:37 INFO - "slavebuilddir": "test" 12:12:37 INFO - } 12:12:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 12:12:37 INFO - Running post-action listener: _resource_record_post_action 12:12:37 INFO - [mozharness: 2016-10-13 19:12:37.162779Z] Finished read-buildbot-config step (success) 12:12:37 INFO - [mozharness: 2016-10-13 19:12:37.162899Z] Running download-and-extract step. 12:12:37 INFO - Running pre-action listener: _resource_record_pre_action 12:12:37 INFO - Running main action method: download_and_extract 12:12:37 INFO - mkdir: /builds/slave/test/build/tests 12:12:37 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')]} 12:12:37 INFO - trying https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 12:12:37 INFO - Downloading https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 12:12:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 12:12:38 INFO - Downloaded 1199 bytes. 12:12:38 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 12:12:38 INFO - Using the following test package requirements: 12:12:38 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:12:38 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:12:38 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 12:12:38 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:12:38 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 12:12:38 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:12:38 INFO - u'jsshell-mac.zip'], 12:12:38 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:12:38 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 12:12:38 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:12:38 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:12:38 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 12:12:38 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:12:38 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 12:12:38 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:12:38 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 12:12:38 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:12:38 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 12:12:38 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:12:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:12:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:12:38 INFO - Fetch https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:12:39 INFO - Http code: 200 12:12:39 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 12:12:39 INFO - x-amz-cf-id: y594JIgkqW7esShVUh376LEaSNcFWLWJ310Rm57NQ39X3EXElQkc4w== 12:12:39 INFO - x-amz-version-id: QGGEAFfdv0bnr0LjTclyW4uNuXjZx7bn 12:12:39 INFO - Expected file size: 16514281 12:12:39 INFO - Obtained file size: 16514281 12:12:46 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:12:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:12:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:12:46 INFO - Fetch https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:12:46 INFO - Http code: 200 12:12:46 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 12:12:46 INFO - x-amz-cf-id: DPKOuOG3Zkxk8_4M1W5udaOvwc646Rpna5tTfs-CCS6bRFCay0QyKQ== 12:12:46 INFO - x-amz-version-id: QGGEAFfdv0bnr0LjTclyW4uNuXjZx7bn 12:12:46 INFO - Expected file size: 16514281 12:12:46 INFO - Obtained file size: 16514281 12:12:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 12:12:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:12:53 INFO - Fetch https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 12:12:54 INFO - Http code: 200 12:12:54 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 12:12:54 INFO - x-amz-cf-id: 5ISWzdrk80O_fUhJIbYlAKNEWM07IvKq5U_1o-uUnhNgOD3dTwwbYQ== 12:12:54 INFO - x-amz-version-id: 6gyYKv3sJBN_0Az1RWgBbv7A5mc4cE5L 12:12:54 INFO - Expected file size: 14409006 12:12:54 INFO - Obtained file size: 14409006 12:12: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')]} 12:12:54 INFO - trying https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:12:54 INFO - Downloading https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 12:12:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 12:12:57 INFO - Downloaded 111676954 bytes. 12:12:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:12:57 INFO - mkdir: /builds/slave/test/properties 12:12:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:12:57 INFO - Writing to file /builds/slave/test/properties/build_url 12:12:57 INFO - Contents: 12:12:57 INFO - build_url:https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:12:58 INFO - Running post-action listener: _resource_record_post_action 12:12:58 INFO - Running post-action listener: set_extra_try_arguments 12:12:58 INFO - [mozharness: 2016-10-13 19:12:58.262490Z] Finished download-and-extract step (success) 12:12:58 INFO - [mozharness: 2016-10-13 19:12:58.262606Z] Running populate-webroot step. 12:12:58 INFO - Running pre-action listener: _resource_record_pre_action 12:12:58 INFO - Running main action method: populate_webroot 12:12:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:12:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:12:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:12:58 INFO - '--tppagecycles', 12:12:58 INFO - '7'], 12:12:58 INFO - 'tests': ['tsvgm']}}, 12:12:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:12:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:12:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:12:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:12:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:12:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:12:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:58 INFO - 'talos_options': ['--disable-e10s'], 12:12:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:12:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:12:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:58 INFO - 'talos_options': ['--disable-e10s'], 12:12:58 INFO - 'tests': ['damp', 'tps']}, 12:12:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:58 INFO - 'tests': ['damp', 'tps']}, 12:12:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:12:58 INFO - 'tests': ['dromaeo_dom']}, 12:12:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:12:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:12:58 INFO - 'tests': ['basic_compositor_video']}, 12:12:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:12:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:12:58 INFO - 'tests': ['a11yr', 12:12:58 INFO - 'ts_paint', 12:12:58 INFO - 'tpaint', 12:12:58 INFO - 'sessionrestore', 12:12:58 INFO - 'sessionrestore_no_auto_restore', 12:12:58 INFO - 'tabpaint']}, 12:12:58 INFO - 'other-e10s': {'tests': ['a11yr', 12:12:58 INFO - 'ts_paint', 12:12:58 INFO - 'tpaint', 12:12:58 INFO - 'sessionrestore', 12:12:58 INFO - 'sessionrestore_no_auto_restore', 12:12:58 INFO - 'tabpaint']}, 12:12:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:12:58 INFO - 'ts_paint', 12:12:58 INFO - 'tpaint', 12:12:58 INFO - 'sessionrestore', 12:12:58 INFO - 'sessionrestore_no_auto_restore', 12:12:58 INFO - 'tabpaint']}, 12:12:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:12:58 INFO - 'ts_paint', 12:12:58 INFO - 'tpaint', 12:12:58 INFO - 'sessionrestore', 12:12:58 INFO - 'sessionrestore_no_auto_restore', 12:12:58 INFO - 'tabpaint']}, 12:12:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:12:58 INFO - 'tests': ['a11yr', 12:12:58 INFO - 'ts_paint', 12:12:58 INFO - 'tpaint', 12:12:58 INFO - 'sessionrestore', 12:12:58 INFO - 'sessionrestore_no_auto_restore', 12:12:58 INFO - 'tabpaint']}, 12:12:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:12:58 INFO - 'tests': ['a11yr', 12:12:58 INFO - 'ts_paint', 12:12:58 INFO - 'tpaint', 12:12:58 INFO - 'sessionrestore', 12:12:58 INFO - 'sessionrestore_no_auto_restore', 12:12:58 INFO - 'tabpaint']}, 12:12:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:12:58 INFO - 'tests': ['tsvgx', 12:12:58 INFO - 'tsvgr_opacity', 12:12:58 INFO - 'tart', 12:12:58 INFO - 'tscrollx', 12:12:58 INFO - 'cart']}, 12:12:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:12:58 INFO - 'tsvgr_opacity', 12:12:58 INFO - 'tart', 12:12:58 INFO - 'tscrollx', 12:12:58 INFO - 'cart']}, 12:12:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:58 INFO - 'talos_options': ['--disable-e10s'], 12:12:58 INFO - 'tests': ['tp5o']}, 12:12:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:58 INFO - 'tests': ['tp5o']}, 12:12:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:58 INFO - 'talos_options': ['--disable-e10s', 12:12:58 INFO - '--xperf_path', 12:12:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:12:58 INFO - 'tests': ['tp5n']}, 12:12:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:58 INFO - 'talos_options': ['--xperf_path', 12:12:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:12:58 INFO - 'tests': ['tp5n']}}, 12:12:58 INFO - 'talos.zip': {'path': '', 12:12:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:12:58 INFO - Running post-action listener: _resource_record_post_action 12:12:58 INFO - [mozharness: 2016-10-13 19:12:58.281347Z] Finished populate-webroot step (success) 12:12:58 INFO - [mozharness: 2016-10-13 19:12:58.281474Z] Running create-virtualenv step. 12:12:58 INFO - Running pre-action listener: _resource_record_pre_action 12:12:58 INFO - Running main action method: create_virtualenv 12:12:58 INFO - Creating virtualenv /builds/slave/test/build/venv 12:12:58 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 12:12:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:12:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:12:58 INFO - Using real prefix '/tools/python27' 12:12:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:12:59 INFO - Installing distribute.............................................................................................................................................................................................done. 12:13:01 INFO - Installing pip.................done. 12:13:01 INFO - Return code: 0 12:13:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:13:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:13:02 INFO - Reading from file tmpfile_stdout 12:13:02 INFO - Output received: 12:13:02 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) 12:13:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:13:02 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')]} 12:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:02 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')]} 12:13:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:02 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 0x10c034490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb44acacc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be48d30>, '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 0x10bc6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be40b20>, '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.gNkrkN0xoE/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.opYDuCxEK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 12:13:02 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 12:13:02 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 12:13:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gNkrkN0xoE/Render', 12:13:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:02 INFO - 'HOME': '/Users/cltbld', 12:13:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:02 INFO - 'LOGNAME': 'cltbld', 12:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:02 INFO - 'MOZ_NO_REMOTE': '1', 12:13:02 INFO - 'NO_EM_RESTART': '1', 12:13:02 INFO - 'PAGER': '/bin/cat', 12:13:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:02 INFO - 'PWD': '/builds/slave/test', 12:13:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:02 INFO - 'SHELL': '/bin/bash', 12:13:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.opYDuCxEK6/Listeners', 12:13:02 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 12:13:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:02 INFO - 'USER': 'cltbld', 12:13:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:02 INFO - 'XPC_FLAGS': '0x0', 12:13:02 INFO - 'XPC_SERVICE_NAME': '0', 12:13:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:02 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 12:13:02 INFO - Cleaning up... 12:13:02 INFO - Return code: 0 12:13:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:13:02 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')]} 12:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:02 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')]} 12:13:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:02 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 0x10c034490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb44acacc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be48d30>, '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 0x10bc6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be40b20>, '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.gNkrkN0xoE/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.opYDuCxEK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 12:13:02 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 12:13:02 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 12:13:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gNkrkN0xoE/Render', 12:13:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:02 INFO - 'HOME': '/Users/cltbld', 12:13:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:02 INFO - 'LOGNAME': 'cltbld', 12:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:02 INFO - 'MOZ_NO_REMOTE': '1', 12:13:02 INFO - 'NO_EM_RESTART': '1', 12:13:02 INFO - 'PAGER': '/bin/cat', 12:13:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:02 INFO - 'PWD': '/builds/slave/test', 12:13:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:02 INFO - 'SHELL': '/bin/bash', 12:13:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.opYDuCxEK6/Listeners', 12:13:02 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 12:13:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:02 INFO - 'USER': 'cltbld', 12:13:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:02 INFO - 'XPC_FLAGS': '0x0', 12:13:02 INFO - 'XPC_SERVICE_NAME': '0', 12:13:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:02 INFO - Downloading/unpacking psutil>=3.1.1 12:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:13:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:13:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:13:06 INFO - Installing collected packages: psutil 12:13:06 INFO - Running setup.py install for psutil 12:13:06 INFO - building 'psutil._psutil_osx' extension 12:13:06 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 12:13:06 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 12:13:06 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 12:13:07 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 12:13:07 INFO - building 'psutil._psutil_posix' extension 12:13:07 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 12:13:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:13:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:13:07 INFO - ^ 12:13:07 INFO - 1 warning generated. 12:13:07 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 12:13:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:13:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:13:07 INFO - Successfully installed psutil 12:13:07 INFO - Cleaning up... 12:13:07 INFO - Return code: 0 12:13:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c034490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb44acacc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be48d30>, '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 0x10bc6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be40b20>, '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.gNkrkN0xoE/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.opYDuCxEK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 12:13:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:13:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:13:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gNkrkN0xoE/Render', 12:13:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:07 INFO - 'HOME': '/Users/cltbld', 12:13:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:07 INFO - 'LOGNAME': 'cltbld', 12:13:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:07 INFO - 'MOZ_NO_REMOTE': '1', 12:13:07 INFO - 'NO_EM_RESTART': '1', 12:13:07 INFO - 'PAGER': '/bin/cat', 12:13:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:07 INFO - 'PWD': '/builds/slave/test', 12:13:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:07 INFO - 'SHELL': '/bin/bash', 12:13:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.opYDuCxEK6/Listeners', 12:13:07 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 12:13:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:07 INFO - 'USER': 'cltbld', 12:13:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:07 INFO - 'XPC_FLAGS': '0x0', 12:13:07 INFO - 'XPC_SERVICE_NAME': '0', 12:13:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:13:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:13:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:13:10 INFO - Installing collected packages: mozsystemmonitor 12:13:10 INFO - Running setup.py install for mozsystemmonitor 12:13:10 INFO - Successfully installed mozsystemmonitor 12:13:10 INFO - Cleaning up... 12:13:10 INFO - Return code: 0 12:13:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:13:10 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')]} 12:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:10 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')]} 12:13:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:10 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 0x10c034490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb44acacc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be48d30>, '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 0x10bc6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be40b20>, '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.gNkrkN0xoE/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.opYDuCxEK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 12:13:10 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 12:13:10 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 12:13:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gNkrkN0xoE/Render', 12:13:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:10 INFO - 'HOME': '/Users/cltbld', 12:13:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:10 INFO - 'LOGNAME': 'cltbld', 12:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:10 INFO - 'MOZ_NO_REMOTE': '1', 12:13:10 INFO - 'NO_EM_RESTART': '1', 12:13:10 INFO - 'PAGER': '/bin/cat', 12:13:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:10 INFO - 'PWD': '/builds/slave/test', 12:13:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:10 INFO - 'SHELL': '/bin/bash', 12:13:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.opYDuCxEK6/Listeners', 12:13:10 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 12:13:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:10 INFO - 'USER': 'cltbld', 12:13:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:10 INFO - 'XPC_FLAGS': '0x0', 12:13:10 INFO - 'XPC_SERVICE_NAME': '0', 12:13:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:10 INFO - Downloading/unpacking jsonschema==2.5.1 12:13:10 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 12:13:10 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 12:13:10 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 12:13:10 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 12:13:10 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 12:13:10 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 12:13:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:13:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:13:13 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 12:13:13 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 12:13:13 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 12:13:13 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 12:13:13 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 12:13:13 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 12:13:13 INFO - Downloading functools32-3.2.3-2.tar.gz 12:13:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:13:13 INFO - warning: no files found matching '*.txt' 12:13:13 INFO - no previously-included directories found matching 'build' 12:13:13 INFO - no previously-included directories found matching 'dist' 12:13:13 INFO - no previously-included directories found matching '.git*' 12:13:13 INFO - Installing collected packages: jsonschema, functools32 12:13:13 INFO - Running setup.py install for functools32 12:13:13 INFO - warning: no files found matching '*.txt' 12:13:13 INFO - no previously-included directories found matching 'build' 12:13:13 INFO - no previously-included directories found matching 'dist' 12:13:13 INFO - no previously-included directories found matching '.git*' 12:13:13 INFO - Successfully installed jsonschema functools32 12:13:13 INFO - Cleaning up... 12:13:14 INFO - Return code: 0 12:13:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c034490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb44acacc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be48d30>, '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 0x10bc6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be40b20>, '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.gNkrkN0xoE/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.opYDuCxEK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 12:13:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:13:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:13:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gNkrkN0xoE/Render', 12:13:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:14 INFO - 'HOME': '/Users/cltbld', 12:13:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:14 INFO - 'LOGNAME': 'cltbld', 12:13:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:14 INFO - 'MOZ_NO_REMOTE': '1', 12:13:14 INFO - 'NO_EM_RESTART': '1', 12:13:14 INFO - 'PAGER': '/bin/cat', 12:13:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:14 INFO - 'PWD': '/builds/slave/test', 12:13:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:14 INFO - 'SHELL': '/bin/bash', 12:13:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.opYDuCxEK6/Listeners', 12:13:14 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 12:13:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:14 INFO - 'USER': 'cltbld', 12:13:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:14 INFO - 'XPC_FLAGS': '0x0', 12:13:14 INFO - 'XPC_SERVICE_NAME': '0', 12:13:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:13:14 INFO - Cleaning up... 12:13:14 INFO - Return code: 0 12:13:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c034490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb44acacc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be48d30>, '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 0x10bc6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be40b20>, '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.gNkrkN0xoE/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.opYDuCxEK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 12:13:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:13:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:13:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gNkrkN0xoE/Render', 12:13:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:14 INFO - 'HOME': '/Users/cltbld', 12:13:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:14 INFO - 'LOGNAME': 'cltbld', 12:13:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:14 INFO - 'MOZ_NO_REMOTE': '1', 12:13:14 INFO - 'NO_EM_RESTART': '1', 12:13:14 INFO - 'PAGER': '/bin/cat', 12:13:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:14 INFO - 'PWD': '/builds/slave/test', 12:13:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:14 INFO - 'SHELL': '/bin/bash', 12:13:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.opYDuCxEK6/Listeners', 12:13:14 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 12:13:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:14 INFO - 'USER': 'cltbld', 12:13:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:14 INFO - 'XPC_FLAGS': '0x0', 12:13:14 INFO - 'XPC_SERVICE_NAME': '0', 12:13:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:14 INFO - Downloading/unpacking blobuploader==1.2.4 12:13:14 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 12:13:14 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 12:13:14 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 12:13:14 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 12:13:14 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 12:13:14 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 12:13:17 INFO - Downloading blobuploader-1.2.4.tar.gz 12:13:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:13:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:13:17 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 12:13:17 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 12:13:17 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 12:13:17 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 12:13:17 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 12:13:17 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 12:13:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:13:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:13:17 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 12:13:17 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 12:13:17 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 12:13:17 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 12:13:17 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 12:13:17 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 12:13:18 INFO - Downloading docopt-0.6.1.tar.gz 12:13:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:13:18 INFO - Installing collected packages: blobuploader, requests, docopt 12:13:18 INFO - Running setup.py install for blobuploader 12:13:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:13:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:13:18 INFO - Running setup.py install for requests 12:13:18 INFO - Running setup.py install for docopt 12:13:19 INFO - Successfully installed blobuploader requests docopt 12:13:19 INFO - Cleaning up... 12:13:19 INFO - Return code: 0 12:13:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:19 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 0x10c034490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb44acacc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be48d30>, '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 0x10bc6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be40b20>, '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.gNkrkN0xoE/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.opYDuCxEK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 12:13:19 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 12:13:19 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 12:13:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gNkrkN0xoE/Render', 12:13:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:19 INFO - 'HOME': '/Users/cltbld', 12:13:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:19 INFO - 'LOGNAME': 'cltbld', 12:13:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:19 INFO - 'MOZ_NO_REMOTE': '1', 12:13:19 INFO - 'NO_EM_RESTART': '1', 12:13:19 INFO - 'PAGER': '/bin/cat', 12:13:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:19 INFO - 'PWD': '/builds/slave/test', 12:13:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:19 INFO - 'SHELL': '/bin/bash', 12:13:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.opYDuCxEK6/Listeners', 12:13:19 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 12:13:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:19 INFO - 'USER': 'cltbld', 12:13:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:19 INFO - 'XPC_FLAGS': '0x0', 12:13:19 INFO - 'XPC_SERVICE_NAME': '0', 12:13:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:13:19 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-q7hi6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:13:19 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-_VPBJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:13:19 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-f22uzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:13:19 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-xvE8pZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:13:19 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-dp9xHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:13:19 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-66YYY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:13:20 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-ujQy6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:13:20 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-7xSVp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:13:20 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-CcsZ_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:13:20 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-OkJH2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:13:20 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-UQJTaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:13:20 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-z6ko9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:13:20 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-LYwTSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:13:20 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-DH33aW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:20 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-cq7lEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:13:21 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-V4HdLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:13:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:13:21 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-cwbHBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:13:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:13:21 INFO - Running setup.py install for manifestparser 12:13:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:13:21 INFO - Running setup.py install for mozcrash 12:13:21 INFO - Running setup.py install for mozdebug 12:13:21 INFO - Running setup.py install for mozdevice 12:13:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:13:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:13:21 INFO - Running setup.py install for mozfile 12:13:21 INFO - Running setup.py install for mozhttpd 12:13:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:13:22 INFO - Running setup.py install for mozinfo 12:13:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:13:22 INFO - Running setup.py install for mozInstall 12:13:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:13:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:13:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:13:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:13:22 INFO - Running setup.py install for mozleak 12:13:22 INFO - Running setup.py install for mozlog 12:13:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:13:22 INFO - Running setup.py install for moznetwork 12:13:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:13:22 INFO - Running setup.py install for mozprocess 12:13:22 INFO - Running setup.py install for mozprofile 12:13:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:13:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:13:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:13:23 INFO - Running setup.py install for mozrunner 12:13:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:13:23 INFO - Running setup.py install for mozscreenshot 12:13:23 INFO - Running setup.py install for moztest 12:13:23 INFO - Running setup.py install for mozversion 12:13:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:13:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:13:23 INFO - Cleaning up... 12:13:23 INFO - Return code: 0 12:13:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:23 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 0x10c034490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb44acacc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be48d30>, '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 0x10bc6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be40b20>, '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.gNkrkN0xoE/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.opYDuCxEK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 12:13:23 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 12:13:23 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 12:13:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gNkrkN0xoE/Render', 12:13:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:23 INFO - 'HOME': '/Users/cltbld', 12:13:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:23 INFO - 'LOGNAME': 'cltbld', 12:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:23 INFO - 'MOZ_NO_REMOTE': '1', 12:13:23 INFO - 'NO_EM_RESTART': '1', 12:13:23 INFO - 'PAGER': '/bin/cat', 12:13:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:23 INFO - 'PWD': '/builds/slave/test', 12:13:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:23 INFO - 'SHELL': '/bin/bash', 12:13:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.opYDuCxEK6/Listeners', 12:13:23 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 12:13:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:23 INFO - 'USER': 'cltbld', 12:13:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:23 INFO - 'XPC_FLAGS': '0x0', 12:13:23 INFO - 'XPC_SERVICE_NAME': '0', 12:13:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:13:24 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-AYBZwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:13:24 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)) 12:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:13:24 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-E4gSQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 12:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:13:24 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-WyPDKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:13:24 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)) 12:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:13:24 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-EE4Bc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:13:24 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)) 12:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:13:24 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-maPL6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:13:24 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)) 12:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:13:24 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-PP7kKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:13:24 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)) 12:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:13:24 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-3a88nk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:13:24 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)) 12:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:13:24 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-U2Eolf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:13:24 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)) 12:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:13:24 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-8gMhhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:13:25 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)) 12:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:13:25 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-eWFglc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:13:25 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)) 12:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:13:25 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-1rx2Gp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:13:25 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)) 12:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:13:25 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-om6QcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:13:25 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)) 12:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:13:25 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-FcCycN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:13:25 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)) 12:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:13:25 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-97MFWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:13:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 12:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:25 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-qJ5Ix4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:25 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)) 12:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:13:25 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-lFzb10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:13:25 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)) 12:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:13:25 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-vrMDpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:13:25 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)) 12:13:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:13:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:13:25 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)) 12:13:25 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)) 12:13:25 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)) 12:13:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:28 INFO - Downloading blessings-1.6.tar.gz 12:13:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:13:28 INFO - Installing collected packages: blessings 12:13:28 INFO - Running setup.py install for blessings 12:13:28 INFO - Successfully installed blessings 12:13:28 INFO - Cleaning up... 12:13:29 INFO - Return code: 0 12:13:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:13:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:13:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:13:29 INFO - Reading from file tmpfile_stdout 12:13:29 INFO - Current package versions: 12:13:29 INFO - blessings == 1.6 12:13:29 INFO - blobuploader == 1.2.4 12:13:29 INFO - docopt == 0.6.1 12:13:29 INFO - functools32 == 3.2.3-2 12:13:29 INFO - jsonschema == 2.5.1 12:13:29 INFO - manifestparser == 1.1 12:13:29 INFO - mozInstall == 1.12 12:13:29 INFO - mozcrash == 0.17 12:13:29 INFO - mozdebug == 0.1 12:13:29 INFO - mozdevice == 0.48 12:13:29 INFO - mozfile == 1.2 12:13:29 INFO - mozhttpd == 0.7 12:13:29 INFO - mozinfo == 0.9 12:13:29 INFO - mozleak == 0.1 12:13:29 INFO - mozlog == 3.4 12:13:29 INFO - moznetwork == 0.27 12:13:29 INFO - mozprocess == 0.23 12:13:29 INFO - mozprofile == 0.28 12:13:29 INFO - mozrunner == 6.12 12:13:29 INFO - mozscreenshot == 0.1 12:13:29 INFO - mozsystemmonitor == 0.3 12:13:29 INFO - moztest == 0.7 12:13:29 INFO - mozversion == 1.4 12:13:29 INFO - psutil == 3.1.1 12:13:29 INFO - requests == 1.2.3 12:13:29 INFO - wsgiref == 0.1.2 12:13:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13:29 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')]} 12:13:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:29 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')]} 12:13:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:29 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 0x10c034490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb44acacc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be48d30>, '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 0x10bc6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be40b20>, '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.gNkrkN0xoE/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.opYDuCxEK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 12:13:29 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 12:13:29 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 12:13:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gNkrkN0xoE/Render', 12:13:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:29 INFO - 'HOME': '/Users/cltbld', 12:13:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:29 INFO - 'LOGNAME': 'cltbld', 12:13:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:29 INFO - 'MOZ_NO_REMOTE': '1', 12:13:29 INFO - 'NO_EM_RESTART': '1', 12:13:29 INFO - 'PAGER': '/bin/cat', 12:13:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:29 INFO - 'PWD': '/builds/slave/test', 12:13:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:29 INFO - 'SHELL': '/bin/bash', 12:13:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.opYDuCxEK6/Listeners', 12:13:29 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 12:13:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:29 INFO - 'USER': 'cltbld', 12:13:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:29 INFO - 'XPC_FLAGS': '0x0', 12:13:29 INFO - 'XPC_SERVICE_NAME': '0', 12:13:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:29 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)) 12:13:29 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)) 12:13:29 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)) 12:13:29 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)) 12:13:29 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)) 12:13:29 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)) 12:13:29 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)) 12:13:29 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)) 12:13:29 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)) 12:13:29 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)) 12:13:29 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)) 12:13:29 INFO - Cleaning up... 12:13:29 INFO - Return code: 0 12:13:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:13:29 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')]} 12:13:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:29 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')]} 12:13:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:29 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 0x10c034490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb44acacc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be48d30>, '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 0x10bc6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be40b20>, '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.gNkrkN0xoE/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.opYDuCxEK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 12:13:29 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 12:13:29 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 12:13:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gNkrkN0xoE/Render', 12:13:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:29 INFO - 'HOME': '/Users/cltbld', 12:13:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:29 INFO - 'LOGNAME': 'cltbld', 12:13:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:29 INFO - 'MOZ_NO_REMOTE': '1', 12:13:29 INFO - 'NO_EM_RESTART': '1', 12:13:29 INFO - 'PAGER': '/bin/cat', 12:13:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:29 INFO - 'PWD': '/builds/slave/test', 12:13:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:29 INFO - 'SHELL': '/bin/bash', 12:13:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.opYDuCxEK6/Listeners', 12:13:29 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 12:13:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:29 INFO - 'USER': 'cltbld', 12:13:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:29 INFO - 'XPC_FLAGS': '0x0', 12:13:29 INFO - 'XPC_SERVICE_NAME': '0', 12:13:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:13:29 INFO - Cleaning up... 12:13:29 INFO - Return code: 0 12:13:29 INFO - Running post-action listener: _resource_record_post_action 12:13:29 INFO - Running post-action listener: _start_resource_monitoring 12:13:29 INFO - Starting resource monitoring. 12:13:29 INFO - [mozharness: 2016-10-13 19:13:29.826093Z] Finished create-virtualenv step (success) 12:13:29 INFO - [mozharness: 2016-10-13 19:13:29.827057Z] Running install step. 12:13:29 INFO - Running pre-action listener: _resource_record_pre_action 12:13:29 INFO - Running main action method: install 12:13:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:13:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:13:30 INFO - Reading from file tmpfile_stdout 12:13:30 INFO - Detecting whether we're running mozinstall >=1.0... 12:13:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:13:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:13:30 INFO - Reading from file tmpfile_stdout 12:13:30 INFO - Output received: 12:13:30 INFO - Usage: mozinstall [options] installer 12:13:30 INFO - Options: 12:13:30 INFO - -h, --help show this help message and exit 12:13:30 INFO - -d DEST, --destination=DEST 12:13:30 INFO - Directory to install application into. [default: 12:13:30 INFO - "/builds/slave/test"] 12:13:30 INFO - --app=APP Application being installed. [default: firefox] 12:13:30 INFO - mkdir: /builds/slave/test/build/application 12:13:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:13:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:14:04 INFO - Reading from file tmpfile_stdout 12:14:04 INFO - Output received: 12:14:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:14:04 INFO - Running post-action listener: _resource_record_post_action 12:14:04 INFO - [mozharness: 2016-10-13 19:14:04.533992Z] Finished install step (success) 12:14:04 INFO - [mozharness: 2016-10-13 19:14:04.534124Z] Running run-tests step. 12:14:04 INFO - Running pre-action listener: _resource_record_pre_action 12:14:04 INFO - Running main action method: run_tests 12:14:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:14:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:14:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:14:04 INFO - 2016-10-13 12:14:04.548 screenresolution[1868:8160] starting screenresolution argv=screenresolution get 12:14:04 INFO - 2016-10-13 12:14:04.551 screenresolution[1868:8160] Display 0: 1600x1200x32@60 12:14:04 INFO - 2016-10-13 12:14:04.557 screenresolution[1869:8162] starting screenresolution argv=screenresolution list 12:14:04 INFO - Available Modes on Display 0 12:14:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:14:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:14:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:14:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:14:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:14:04 INFO - Intel Iris: 12:14:04 INFO - Chipset Model: Intel Iris 12:14:04 INFO - Type: GPU 12:14:04 INFO - Bus: Built-In 12:14:04 INFO - VRAM (Dynamic, Max): 1536 MB 12:14:04 INFO - Vendor: Intel (0x8086) 12:14:04 INFO - Device ID: 0x0a2e 12:14:04 INFO - Revision ID: 0x0009 12:14:04 INFO - Displays: 12:14:04 INFO - Display: 12:14:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:14:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:14:04 INFO - Display Serial Number: 1600x1200 60 12:14:04 INFO - Main Display: Yes 12:14:04 INFO - Mirror: Off 12:14:04 INFO - Online: Yes 12:14:04 INFO - Rotation: Supported 12:14:04 INFO - Return code: 0 12:14:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:14:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:14:04 INFO - Python 2.7.3 12:14:04 INFO - Return code: 0 12:14:04 INFO - grabbing minidump binary from tooltool 12:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:04 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 0x10be48d30>, '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 0x10bc6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be40b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:14:04 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 12:14:04 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 12:14:04 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 12:14:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:14:04 INFO - Return code: 0 12:14:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:14:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:14:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:14:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:14:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:14:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0269', '--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 12:14:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0269 --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 12:14:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gNkrkN0xoE/Render', 12:14:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:04 INFO - 'HOME': '/Users/cltbld', 12:14:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:04 INFO - 'LOGNAME': 'cltbld', 12:14:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:14:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:14:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:04 INFO - 'MOZ_NO_REMOTE': '1', 12:14:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:14:04 INFO - 'NO_EM_RESTART': '1', 12:14:04 INFO - 'PAGER': '/bin/cat', 12:14:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:04 INFO - 'PWD': '/builds/slave/test', 12:14:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:14:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:04 INFO - 'SHELL': '/bin/bash', 12:14:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.opYDuCxEK6/Listeners', 12:14:04 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 12:14:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:04 INFO - 'USER': 'cltbld', 12:14:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:04 INFO - 'XPC_FLAGS': '0x0', 12:14:04 INFO - 'XPC_SERVICE_NAME': '0', 12:14:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0269', '--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 12:14:05 INFO - mozversion application_buildid: 20161013094723 12:14:05 INFO - mozversion application_changeset: 7452437b3ab571b1d60aed4e973d82a1471f72b2 12:14:05 INFO - mozversion application_display_name: Nightly 12:14:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:14:05 INFO - mozversion application_name: Firefox 12:14:05 INFO - mozversion application_remotingname: firefox 12:14:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:14:05 INFO - mozversion application_vendor: Mozilla 12:14:05 INFO - mozversion application_version: 52.0a1 12:14:05 INFO - mozversion platform_buildid: 20161013094723 12:14:05 INFO - mozversion platform_changeset: 7452437b3ab571b1d60aed4e973d82a1471f72b2 12:14:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:14:05 INFO - mozversion platform_version: 52.0a1 12:14:05 INFO - using testdate: 1476386045 12:14:05 INFO - actual date: 1476386045 12:14:05 INFO - starting webserver on 'localhost:49278' 12:14:05 INFO - SUITE-START | Running 2 tests 12:14:05 INFO - TEST-START | dromaeo_css 12:14:05 INFO - operating with platform_type : mac_ 12:14:05 INFO - Initialising browser for dromaeo_css test... 12:14:05 INFO - TEST-INFO | started process 1879 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmpkhaeSA/profile http://localhost:49278/getInfo.html) 12:14:07 INFO - PROCESS | 1879 | __metrics Screen width/height:1600/1200 12:14:07 INFO - PROCESS | 1879 | colorDepth:24 12:14:07 INFO - PROCESS | 1879 | Browser inner width/height: 1024/689 12:14:07 INFO - PROCESS | 1879 | __metrics 12:14:07 INFO - PROCESS | 1879 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:14:07 INFO - PROCESS | 1879 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:14:08 INFO - TEST-INFO | 1879: exit 0 12:14:08 INFO - Browser initialized. 12:14:08 INFO - Running cycle 1/1 for dromaeo_css test... 12:14:08 INFO - TEST-INFO | started process 1881 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmpkhaeSA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:14:20 INFO - PROCESS | 1881 | RSS: Main: 253956096 12:14:20 INFO - PROCESS | 1881 | 12:15:12 INFO - PROCESS | 1881 | 1476386111999 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] 12:17:24 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 12:17:26 INFO - PROCESS | 1881 | RSS: Main: 423243776 12:17:26 INFO - PROCESS | 1881 | 12:20:29 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 12:20:29 INFO - PROCESS | 1881 | RSS: Main: 390057984 12:20:29 INFO - PROCESS | 1881 | 12:23:32 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 12:23:32 INFO - PROCESS | 1881 | RSS: Main: 377909248 12:23:32 INFO - PROCESS | 1881 | 12:26:35 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 12:26:35 INFO - PROCESS | 1881 | RSS: Main: 350154752 12:26:35 INFO - PROCESS | 1881 | 12:29:39 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 12:29:39 INFO - PROCESS | 1881 | RSS: Main: 340316160 12:29:39 INFO - PROCESS | 1881 | 12:32:43 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 12:32:43 INFO - PROCESS | 1881 | RSS: Main: 401440768 12:32:43 INFO - PROCESS | 1881 | 12:32:43 INFO - PROCESS | 1881 | __start_tp_report 12:32:43 INFO - PROCESS | 1881 | _x_x_mozilla_page_load 12:32:43 INFO - PROCESS | 1881 | _x_x_mozilla_page_load_details 12:32:43 INFO - PROCESS | 1881 | |i|pagename|runs| 12:32:43 INFO - PROCESS | 1881 | |0;dojo.html;3314.188023934136;3319.601647802264;3354.563906692128;3366.394049071166;3392.6776956189155;5440.891182176354;5553.777848886045;5564.888702225953;5644.74598643061;5661.886762264752;5581.079121944885;5584.692841893694;5646.632968857021;5670.376258611549;5701;5024.673396229252;5064.290999260106;5072.8731781705565;5124.948750512484;5137.922931156036;5132.640715149941;5220.686758794465;5232.45059268777;5235.633505654606;5285.973570132144;5031.84904452867;5194.402643695986;5203.973980130113;5222.242774797652;5283.313169287987;5079.568236699885;5144.871378215537;5149.150390185615;5152.175651895716;5161.4322424533275;5035.132870184405;5112.514311140436;5113.258577506278;5124.769385377667;5127.589792816603;4849.884526558876;5559.027170245191;5575.275214222166;5576.079946808813;5599.524040456566;5788.131780232999;5977.491913187384;5987.550933679991;5992.161097446317;5998.40015998401;6072.848178795522;6093;6115.571909966304;6122.693865306717;6127.142200091991;5579.944200557983;5623.606347555632;5652.293463317044;5657.688827114507;5691.4877661010705;6326.145970294044;6341.682915854189;6344.68276586178;6349.079383489368;6359.968200159062;5476.205950137208;5946.85132871673;5977.402259773988;5983.311919129269;5983.461488466059;5781.392953739797;5897.085951677497;5984.281886173687;5990.341062483123;6157.9384206157365;6044.123602077674;6089.668886651826;6098.9049174467955;6102.51954821894;6110.59976616339;5068.949310506921;6119.357467465941;6127.172831667668;6140.263168419729;6143.32423433422;4740.075685241345;4793.640476964241;4851.660383773173;4852.636052296021;4862.95137048625;4305.526392096866;4306.332518459643;4351.6301114405;4352.455943007124;4406.823727050882;5672.858178545569;5674.801381951612;5689.260396148474;5691.516221121168;5693.829185124453;6179.351168127282;6187.010078387437;6189.566730328834;6206.348333425016;6226.533010024266;5522.641028333145;5544.1960915667005;5590.776368945278;5615.989121958005;5657.971710141423;5271.998320319127;5314.176302673091;5340.652857564246;5349.304590403222;5370.4361042090795;6309.621422714652;6323.9051414228825;6351.968240158954;6362.809115726351;6365.777197798055;5964.522838173022;6088;6214.689265536796;6217.502599791916;6223.253209614875;6295.150154729053;6325.96837015812;6334.176557047738;6339.96830015847;6346.048092786119;6046.365131661112;6058.7273572688455;6079.756809727738;6087.443779412026;6108.08378743192;6098.634081955097;6160.767846430642;6163.938360616516;6200.038993955944;6213.440790328892;4852.781624826821;4909.459959404534;5677;5737.540996720169;5925.022371308686;5948.048312270189;5948.40515948419;5952.285725712942;5954.702264886825;5995.220621319375;5803.593748437569;5807.274090738658;5818.563607729353;5820.70896455184;5826.446487583673;5741.885162296647;5828.388019258086;5831.183634291117;5863.355030946678;5871.6183448075735;5747.8563035924435;5797.913031304618;5817.360090390139;5850.53195744348;5861.7655293787775 12:32:43 INFO - PROCESS | 1881 | |1;ext.html;466.3517710382568;485.7012937043719;496.52581784395903;539.325842696629;552.61869310176;31053.534196987035;39991.40012899805;40999.18001639965;41020.99999999996;43301.35047974283;42905;43249.7837510812;43289.56710432895;43290.13419731604;43345.6996290111;9520.857187142181;9717.562709678046;9723.222142228604;9729.902700973005;9744.269179811507;7703.653335599884;8765.211130998208;11651;11661.358625275607;11952.163348565604;11835.704107397298;11878.8715072068;11894.524219031271;11920.642380728592;11994.100442466806;11733.413329333542;11820.822687659693;11856.288622682581;11859.110566707444;11863.881361186364;11555.422228888607;11570.78506756787;11632.883671163265;11633.825492617618;11640.825387619192;40427.59572404297;40794.184116317505;40803;40838;40874.591254087376;3189.8086114832954;3204.3591281743743;3301.6533264007176;3654.451832225161;4021.2761702893467;30504.237394065105;31146;31722.206944826332;32418.6758132418;32428;3199.680031996805;3234.9518039929676;3288.19439237386;3375.240570871534;3389.881354152593;475874.24125758646;477088;478114;478799;488595.55702221615;51076.74461627668;61399.77200455966;61970.69014654898;61995.07007394802;62543;62528;64528.35471645317;64975;65054.34945650483;65105.67447162734;15375.308111135026;15641.452549160953;15653.90422670425;15676.05943643385;15680.529584112495;10790.352578845295;11032.172587056002;11549.88450115488;11860.288382697066;12029.879701203052;15647.374105035899;16234.918825405797;16352.100634465218;18850.24599016024;19302.4209273722;7757.301842834172;7790.844183116419;7826.8434631307055;7851.214878512217;7980.481268717631;815.8653822119284;856.7087190355309;933.4737703854112;953.7043516509904;1058.6347710040025;27116.728832711815;30354.84822575873;30743.385132297677;31539;31930.361392772014;899.6671231644334;962.2013728605381;1024.472396715692;1030.3611760708259;1094.4582431696201;15023.248837558296;15451.84548154504;15671.764923526156;15722.528324150293;15901.363945442054;562165;662739;663767;664369.6781516061;665372.6731366313;57158.7142064287;57529.712351438815;57690.13464798031;57782.7110864443;57796.71101644465;1214.993925030387;1248.8876001159758;1310.6919873829643;1341.5170538606094;1366.214426704629;29972.100836974925;30589.23526911845;31257;31472.213194670316;32172.83913580417;50148.74925625348;55803;57114.7144264276;57134.42865571291;59300;531747;598522;602137;603973;604387;26400.811963461307;27562.862185688944;27955.580666289614;28218.71781282243;42733.78633106814;46378.84052898704;46984.29523557081;47742.80642983953;48103.518964809904;48633.51366486432;39466.605333946296;39479;39583;39828.2034359317;41090.58909410987;39375.40936885892;39984.600153998086;40235.59764402318;40639;40868.79565602272;38612.80693596514;39267.21465570616;39305;39449;39562.40656390214;32257;34148;34506.82746586251;34657.65342346544;35011.12472188216;22947.6557851629;25493.745062549136;25546.339487933226;25683.87158064198;26376.472470550867 12:32:43 INFO - PROCESS | 1881 | |2;jquery.html;352.6315000824139;371.5597017534222;372.56410000299627;380.63839352615025;402.436588775714;14911.627209319759;14991.676290175026;15631.687366252683;19850.007499625033;20744.066517006733;19182.712259316086;19429.31997380092;20060.799392006076;20188.495287617818;20339.28812491563;5553.500184983351;5846.298444186704;5859.589828711992;5859.736311865976;5861.325947516048;5013.699178049329;6374.776882809103;6453.677316134198;6495.415412612864;6522.282548919615;6123.295820980599;6316.715747791361;6420.7527365421965;6445.097686323918;6455.515836312272;6327.873442531123;6363.936360636404;6421.5825971311715;6428.485721142298;6435.163428754256;5397.325334333208;5518.668879867221;5519.503244707956;5561.082421400464;5575.581831362623;23980.52038959211;24444.633330500055;24986.750132498622;25213.87393063023;25226.621600676004;2167.8157356624547;2281.600719874032;2349.7180338359335;2599.324175714309;2759.0619189475474;14751.40994360235;15031.473898413504;15384.384624615002;15529.145896975662;15610.29753661088;1702.8978261304362;1735.722284434497;1763.9823601763946;1806.3406856497415;1900.0594705620667;87728.684069739;112371;112802.87197128005;114611.28083078761;115374;8110;9535.666251681158;9608.327417080876;10127.291089623655;10352;12127.272363658209;12137.635870923887;12215.938920305342;12235.877641223651;12280.140390172603;7585.469017168855;7618.6190690465255;7653.540787552764;7792.8051798704355;7798.961005195051;7893.802654933673;7925.8811117833275;7972.760817175378;8109;8162.102168761431;3689.7970611616083;3758.1544152566003;3759.7180211484247;3813.56144043433;3815.847366105325;1840.88954662718;1876.7372567840514;1883.4949517142168;1895.687211610069;1895.6966885298286;839.3536976528161;840.7015509494041;912.7626817027488;934.4159900062461;965.7054598347431;20060.89969550143;20721.792782072287;20739.585208296052;20834.47913802137;21023.95425097996;871.7036207689416;883.779055236191;896.1665650944684;911.3574929674563;1022.8363461846218;8512.74461766148;10309.639162629423;10922.344659320308;11026.393548354918;11051.447427628586;463204;479036;484796;581985;583589;77970.61014694891;95896.52051739697;106041;120217.79782202066;140413;938.6245501799335;965.6234068713208;992.2706810494425;1003.9949800250952;1017.7455636090978;17781.1998460067;18080.72878906844;18525.258989640264;18713.877167370003;20215;44056.559434405244;58330;58812;59172.22483325507;59187.81624367575;60813.087803682094;80745;81608;82015.5899220524;82813;5703.572232082527;7616.857471379338;8074.636641351036;8237.83524329522;8438.746837594881;11946.76106477883;12455.501779928702;12903.354832258161;12997.415116319598;13132.540361087316;9933.00669933001;10656.467176641292;10863.945680271549;11261.324320540794;11432.885671143182;10834.620788272372;11159.38623375722;11185.328880266885;11516.827247591125;11606.071514278668;8475.618597163266;10347;10851.728706782395;10899.836502452628;11474.426278685865;7743.303102720557;7892.250236227549;8033.5983200838555;8180.222878826502;8318.209770071833;8184.7953801155445;10568.577256909637;10987.340759554452;11383.260088094248;11468.311901285615 12:32:43 INFO - PROCESS | 1881 | |3;mootools.html;1492.9029613075145;1500.6923580665964;1680.722680757675;1812.4019073705679;1859.8047205043479;8291.58542072897;8815.691450799224;8828.984666763325;8843;8846.292296616264;7786.1435242123325;8042.437029407944;8042.839143217147;8361.414700970934;8642.611082501287;2291.5302363015626;2329.301209637102;2330.149495434171;2331.8600883946933;2336.2290444153386;2311.0986715181093;2316.895739691718;2343.695319608457;2354.6585745066955;2357.56385068762;2296.9885150574223;2299.839011269212;2300.884955752214;2307.5038866643667;2355.0462062864567;1933.2073849721546;2308.4459729664845;2319.0375993962653;2321.93034208974;2322.698049253603;2296.4258935266184;2320.6054970655027;2322.8606283622867;2324.0819876148994;2327.6392159215347;8531.677589973564;8711.782205444915;8752.912470875273;8828.690995815115;8835.381523293372;4496.752678602676;5220.8694782630355;5228.215767634847;5230.947690523084;5235.55497782689;3247.2044349134376;3273.854151047138;3332.0836769888233;3442.122258823984;3543.787372757643;955.8911662471569;1065.4672663668166;1066.9626563070333;1067.765091679837;1091.7052395853154;11458.94270528642;11486.310821350662;11488.36813975231;11503.309801411942;11515.36665483398;4384.956150438518;4687.343771871941;4703.576678098988;4734.881627959328;4741.644376671695;9682.854757178648;9857.901420985841;9861.063198996084;9878.259130565237;9879.579503021661;5183.248428977777;5183.948160518422;5190.143626301618;5191.8182863599595;5192.221166824931;4553.248713962225;4696.577308042292;4733.384659994179;4740.26525888481;4754.809807607726;3630.41913293877;3664.5602527696847;3668.2663467306647;3753.530808648919;3762.887113386603;1123.309164863603;1126.1159989408275;1134.931904085741;1138.703936976392;1141.0928311991986;797.5175019113353;837.660747397305;884.3544212724747;889.8698652711021;931.2922179143764;5192.480751924777;5653.434656534396;5730.426957304237;5902.704864756745;6135.877282454415;817.1828171828172;958.8561715742555;986.496886587831;1016.4612755239586;1021.933574317667;3647.124690074363;3877.9806100969313;3908.9609103909165;3966.781826999485;3969.940450893181;8996.8650470243;10356.119729823049;10696.251262411557;10700.518476668569;10727.088197503144;10557.577696892193;10666.306690065121;10668.466576671135;10672.146228301717;10687.946560267304;7617.352525035434;8052.959735201403;8075.232852878967;8170.468919520212;8199.01611806575;5400.4059553448305;5664.943350566442;6103.542234332532;6111.663858487826;6135.141080248858;9340.81318373615;9417.916939552038;9851.261155413229;9924.1068303853;10089.545970431494;8958.328125390492;9163.816723665623;9924;9928;10284.434356110485;6876.69054892541;7300.087489063867;7498.775036748906;7538.6607602659105;7618.847623047619;8084;8110.270075693216;8114.675412983414;8176.877346839921;8220.095789463036;7413.369863561657;7467.738629147953;7488.213737557478;7530.774076777706;7550.131734850563;7047.436205103682;7237.312455316736;7239.095113110861;7307.050083489113;7386.815329616803;6690.19717633887;6820.62486563224;6829.863402732017;6831.521793474608;6833.760818371333;4898.387701537308;4982.7259500727805;5046.899062018812;5186.662866913639;5195.350581177353;5063.924041139459;5076.771545280398;5096.0827051131155;5226.163813789927;5394.56843452515 12:32:43 INFO - PROCESS | 1881 | |4;prototype.html;293.58457782238133;301.1132215624984;303.41592434563466;308.18638793584927;308.3232305090328;5031.874203144918;5039.470855560168;5040.37900291848;5061.569766569842;5080.110980578403;4095.385692146172;4363.410939523172;4750.429948406196;5005.6746311489815;5018.146915024446;3660.6888414484733;3694.390425579776;3814;3841.3661745811905;3859.8842034739005;2980.4188183304154;3311.989843097854;3875.961240387602;3878.5151856017997;3909.76541407515;3541.8937431876957;3563.536740223767;3834.6740527055094;3859.787711675871;3860.9227815443674;2922.8976985805393;3499.4400895856797;3636.799976001319;3844.136118591639;3849.826757795906;3316.817575033372;3361.714254288364;3369.5451114099774;3371.865125394981;3401.4047541680357;4021.879343619696;4881.951180488185;4902.681325713817;4930.260460930853;4948.653594248404;3965.246603145422;3968.3055465293746;4159.729617574848;4386.758728269944;4438.4451943507065;5073.121382983448;5205.245239440298;5230.895382092375;5254.8686282842855;5268.315119034501;3679.8344074516713;3701.5188025556504;3725.2735716535285;3725.8882233533036;3736.8505259789576;5293.470652934714;5562.3047119110115;5576.275084239023;5589.540602135047;5604.747786349583;3416.299658570037;3636.781793092423;3665.5601327840295;3704.5184126063978;3792.7345085843726;3725.869594564177;3729.1422972716427;3730.8880733578035;3748.850045998184;3766.981165094157;4344.23975804233;4362.578768783355;4407.096545208225;4408.58118478744;4409.2724700424715;4473.7986790594505;5248.921266181011;5331.466853314638;5436.809711660152;5456.727163641882;1197.550918405598;1268.1820225954207;1281.7436512697498;1287.3949243855373;1290.2258644813037;640.6380395076767;659.6866488417963;788.333857890082;788.8974433323746;792.8453951479521;3771.585125636178;4001.6786067564435;4118.364246881998;4161.313383291723;4843.370361852936;4399.824007039682;4513.887152821206;5286.273177409917;5294.894102117936;5323.893522129536;4175.373693945932;4722.716637001722;5015.3730783652045;5023.773930173151;5030.3963524377305;2441.9865755711535;2468.321211666806;2716.293763621473;2765.783055455593;2866.942661146807;4917.041176970456;5617.859553511195;5657.434256574392;5666.575006874501;5674.886502269931;5333.040052790458;5459.590530710213;5467.922189567938;5545.334559852763;5554.805716770914;4362.716423432466;5586.86032849182;5640.943590564042;5653.265075540236;5682.232898558725;4323.54602766705;4724.598409135262;5193.662411943212;5410.891782164414;5452.454754524674;5002.474245355202;5331.466853314638;5365.624406291522;5372;5380.847660935657;5183.922241166461;5184.9740751296;5570.805021824217;5579.414161513145;5615.568030152285;3642;3648.8540458381367;3651.634836516327;3674.6141655125825;3696.4824924511126;4671.509491503343;5043.823466178666;5115.974420127875;5349.358077030625;5472.343318801769;5299.682019078868;5300.151975684026;5307.867303317448;5315.893682126413;5431.158170483582;4304.720193187432;4672.523562511874;5270.4202537721585;5284.339457567804;5294.602904782079;4822.879428014187;5200.089984252816;5207.401148867929;5221.556167725786;5251.36983561975;4855.927161092657;5352.812651557177;5354.7858085676135;5366.356037275552;5367.570594352364;4262.744235345889;4696.92954605688;5115.10741025808;5146.0737067326745;5175.896482070413 12:32:43 INFO - PROCESS | 1881 | |5;yui.html;693.9028536004959;729.1833146875497;749.4042236422041;785.8821176823479;835.3484282259838;13513.864861351383;13653.590392288235;14210.360533775993;14211.07628004179;14328.641783955407;12852.421641026152;12960.15758975666;12997.155184912974;13030.413631386587;13053.67365815855;658.4436151452039;825.2984962781633;851.6040041380757;852.9786755331104;853.1425916953453;676.3507033248088;676.5365724478747;677.0926957876444;677.197520937688;827.0902007791442;893.3701740273092;896.8968568614595;898.3397203055749;899.7615631857562;901.2789768185459;802.9036515618105;882.5190271302156;890.8173824365989;894.4990805149072;894.8344556257114;515.9019786240602;532.8028629407144;661.2032500836528;663.4625952978104;670.4803777072759;13109.082364234508;13589.796153057712;13637;13990.580282591436;14003.71992560143;14174.858251417458;14583.477873036261;14602.707945841024;14730;14767.483138090223;9648;11515.399427322911;11639.59261425846;11668.35824029678;12012;3681.9631803682155;3723.087843478338;3735.34631439497;3738.2710371477574;3768.208676178006;110955;111726.44136779345;112032;114859.42570287095;115834.26248606277;10573.661481448251;10793.730156746144;10866.293690910066;10957.50691218897;11291.774164516828;22613;22866.42833929165;22912.4271893204;23073.769262307163;23267;7385.261473852572;7393.149787774475;7457.552546847207;7485.925140748632;7511.849763004819;4111.424400583921;4198.580141985838;4248.171606536695;4333.634905004977;4474.7762611869275;3447;3588.1926566522325;3591.2996965591983;3593.5328407307406;3601.8559257629927;649.4706813946618;658.2002866517205;661.6096503063216;664.0901964308831;672.579637726421;529.5350145074607;568.3804652928328;653.9247986481521;660.5211221864206;671.9059331693567;8250.793730156674;9182.449053056838;9195.264378849675;9215.170634642915;9495.192908602847;601.7983975368301;630.3727790848136;656.540421704799;656.7143292667705;660.1681880830189;4491.8427855025575;4549.772511374418;4555.476120246148;4567.474740404896;4610.815567377334;95218.52390738002;95586;149656;161462.19268903814;162764;139934;144609;146799.79800303004;147527.2623636875;149105;85162.72255916166;105897.47051264695;108752;109826.45086774515;111989.44137262103;8166;8393.160683931803;8658.393912426322;8820.25027872638;8961.641534338554;57740.422595775184;67353;68972;69159.30840691528;71192.64403678155;61472;96267;102299.48850256;104308.47845761027;104461;11090.500927458444;11331.943340283246;11348.886511134782;11689.298642081503;11743.354115523729;12332.938335308267;12417.565385211474;12597.559085431965;12774;13005.934970325088;11652.30086194831;11894.226875253082;12315.69210769738;12438.751224975269;12470.251784892935;10919.344839309666;11836.76326473483;12201.267923924594;13684;14022.579322620337;12885.162464439782;12889.613311600668;13150.21098734079;13162.24710567462;13297.867021329663;10410.531526081193;10988.395638239974;11091.116309759682;11435.828462572901;12023.4589443477;8557.74326770173;9158.908410915805;11293.265937714023;11369.943150284196;11471.139664525028 12:32:43 INFO - PROCESS | 1881 | __end_tp_report 12:32:43 INFO - PROCESS | 1881 | __start_cc_report 12:32:43 INFO - PROCESS | 1881 | _x_x_mozilla_cycle_collect,1755 12:32:43 INFO - PROCESS | 1881 | __end_cc_report 12:32:43 INFO - PROCESS | 1881 | __startTimestamp1476387163441__endTimestamp 12:32:43 INFO - PROCESS | 1881 | 12:32:43 INFO - PROCESS | 1881 | ------- Summary: start ------- 12:32:43 INFO - PROCESS | 1881 | Number of tests: 6 12:32:43 INFO - PROCESS | 1881 | 12:32:43 INFO - PROCESS | 1881 | [#0] dojo.html Cycles:165 Average:5620.20 Median:5747.86 stddev:630.36 (11.0%) stddev-sans-first:605.78 12:32:43 INFO - PROCESS | 1881 | Values: 3314.2 3319.6 3354.6 3366.4 3392.7 5440.9 5553.8 5564.9 5644.7 5661.9 5581.1 5584.7 5646.6 5670.4 5701.0 5024.7 5064.3 5072.9 5124.9 5137.9 5132.6 5220.7 5232.5 5235.6 5286.0 5031.8 5194.4 5204.0 5222.2 5283.3 5079.6 5144.9 5149.2 5152.2 5161.4 5035.1 5112.5 5113.3 5124.8 5127.6 4849.9 5559.0 5575.3 5576.1 5599.5 5788.1 5977.5 5987.6 5992.2 5998.4 6072.8 6093.0 6115.6 6122.7 6127.1 5579.9 5623.6 5652.3 5657.7 5691.5 6326.1 6341.7 6344.7 6349.1 6360.0 5476.2 5946.9 5977.4 5983.3 5983.5 5781.4 5897.1 5984.3 5990.3 6157.9 6044.1 6089.7 6098.9 6102.5 6110.6 5068.9 6119.4 6127.2 6140.3 6143.3 4740.1 4793.6 4851.7 4852.6 4863.0 4305.5 4306.3 4351.6 4352.5 4406.8 5672.9 5674.8 5689.3 5691.5 5693.8 6179.4 6187.0 6189.6 6206.3 6226.5 5522.6 5544.2 5590.8 5616.0 5658.0 5272.0 5314.2 5340.7 5349.3 5370.4 6309.6 6323.9 6352.0 6362.8 6365.8 5964.5 6088.0 6214.7 6217.5 6223.3 6295.2 6326.0 6334.2 6340.0 6346.0 6046.4 6058.7 6079.8 6087.4 6108.1 6098.6 6160.8 6163.9 6200.0 6213.4 4852.8 4909.5 5677.0 5737.5 5925.0 5948.0 5948.4 5952.3 5954.7 5995.2 5803.6 5807.3 5818.6 5820.7 5826.4 5741.9 5828.4 5831.2 5863.4 5871.6 5747.9 5797.9 5817.4 5850.5 5861.8 12:32:43 INFO - PROCESS | 1881 | 12:32:43 INFO - PROCESS | 1881 | [#1] ext.html Cycles:180 Average:70999.74 Median:39457.80 stddev:153630.11 (389.4%) stddev-sans-first:153969.81 12:32:43 INFO - PROCESS | 1881 | Values: 466.4 485.7 496.5 539.3 552.6 31053.5 39991.4 40999.2 41021.0 43301.4 42905.0 43249.8 43289.6 43290.1 43345.7 9520.9 9717.6 9723.2 9729.9 9744.3 7703.7 8765.2 11651.0 11661.4 11952.2 11835.7 11878.9 11894.5 11920.6 11994.1 11733.4 11820.8 11856.3 11859.1 11863.9 11555.4 11570.8 11632.9 11633.8 11640.8 40427.6 40794.2 40803.0 40838.0 40874.6 3189.8 3204.4 3301.7 3654.5 4021.3 30504.2 31146.0 31722.2 32418.7 32428.0 3199.7 3235.0 3288.2 3375.2 3389.9 475874.2 477088.0 478114.0 478799.0 488595.6 51076.7 61399.8 61970.7 61995.1 62543.0 62528.0 64528.4 64975.0 65054.3 65105.7 15375.3 15641.5 15653.9 15676.1 15680.5 10790.4 11032.2 11549.9 11860.3 12029.9 15647.4 16234.9 16352.1 18850.2 19302.4 7757.3 7790.8 7826.8 7851.2 7980.5 815.9 856.7 933.5 953.7 1058.6 27116.7 30354.8 30743.4 31539.0 31930.4 899.7 962.2 1024.5 1030.4 1094.5 15023.2 15451.8 15671.8 15722.5 15901.4 562165.0 662739.0 663767.0 664369.7 665372.7 57158.7 57529.7 57690.1 57782.7 57796.7 1215.0 1248.9 1310.7 1341.5 1366.2 29972.1 30589.2 31257.0 31472.2 32172.8 50148.7 55803.0 57114.7 57134.4 59300.0 531747.0 598522.0 602137.0 603973.0 604387.0 26400.8 27562.9 27955.6 28218.7 42733.8 46378.8 46984.3 47742.8 48103.5 48633.5 39466.6 39479.0 39583.0 39828.2 41090.6 39375.4 39984.6 40235.6 40639.0 40868.8 38612.8 39267.2 39305.0 39449.0 39562.4 32257.0 34148.0 34506.8 34657.7 35011.1 22947.7 25493.7 25546.3 25683.9 26376.5 12:32:43 INFO - PROCESS | 1881 | 12:32:43 INFO - PROCESS | 1881 | [#2] jquery.html Cycles:180 Average:31877.39 Median:372.06 stddev:87186.60 (23433.4%) stddev-sans-first:87399.05 12:32:43 INFO - PROCESS | 1881 | Values: 352.6 371.6 372.6 380.6 402.4 14911.6 14991.7 15631.7 19850.0 20744.1 19182.7 19429.3 20060.8 20188.5 20339.3 5553.5 5846.3 5859.6 5859.7 5861.3 5013.7 6374.8 6453.7 6495.4 6522.3 6123.3 6316.7 6420.8 6445.1 6455.5 6327.9 6363.9 6421.6 6428.5 6435.2 5397.3 5518.7 5519.5 5561.1 5575.6 23980.5 24444.6 24986.8 25213.9 25226.6 2167.8 2281.6 2349.7 2599.3 2759.1 14751.4 15031.5 15384.4 15529.1 15610.3 1702.9 1735.7 1764.0 1806.3 1900.1 87728.7 112371.0 112802.9 114611.3 115374.0 8110.0 9535.7 9608.3 10127.3 10352.0 12127.3 12137.6 12215.9 12235.9 12280.1 7585.5 7618.6 7653.5 7792.8 7799.0 7893.8 7925.9 7972.8 8109.0 8162.1 3689.8 3758.2 3759.7 3813.6 3815.8 1840.9 1876.7 1883.5 1895.7 1895.7 839.4 840.7 912.8 934.4 965.7 20060.9 20721.8 20739.6 20834.5 21024.0 871.7 883.8 896.2 911.4 1022.8 8512.7 10309.6 10922.3 11026.4 11051.4 463204.0 479036.0 484796.0 581985.0 583589.0 77970.6 95896.5 106041.0 120217.8 140413.0 938.6 965.6 992.3 1004.0 1017.7 17781.2 18080.7 18525.3 18713.9 20215.0 44056.6 58330.0 58812.0 59172.2 59187.8 60813.1 80745.0 81608.0 82015.6 82813.0 5703.6 7616.9 8074.6 8237.8 8438.7 11946.8 12455.5 12903.4 12997.4 13132.5 9933.0 10656.5 10863.9 11261.3 11432.9 10834.6 11159.4 11185.3 11516.8 11606.1 8475.6 10347.0 10851.7 10899.8 11474.4 7743.3 7892.3 8033.6 8180.2 8318.2 8184.8 10568.6 10987.3 11383.3 11468.3 12:32:43 INFO - PROCESS | 1881 | 12:32:43 INFO - PROCESS | 1881 | [#3] mootools.html Cycles:180 Average:5609.70 Median:5185.31 stddev:3140.26 (60.6%) stddev-sans-first:3133.83 12:32:43 INFO - PROCESS | 1881 | Values: 1492.9 1500.7 1680.7 1812.4 1859.8 8291.6 8815.7 8829.0 8843.0 8846.3 7786.1 8042.4 8042.8 8361.4 8642.6 2291.5 2329.3 2330.1 2331.9 2336.2 2311.1 2316.9 2343.7 2354.7 2357.6 2297.0 2299.8 2300.9 2307.5 2355.0 1933.2 2308.4 2319.0 2321.9 2322.7 2296.4 2320.6 2322.9 2324.1 2327.6 8531.7 8711.8 8752.9 8828.7 8835.4 4496.8 5220.9 5228.2 5230.9 5235.6 3247.2 3273.9 3332.1 3442.1 3543.8 955.9 1065.5 1067.0 1067.8 1091.7 11458.9 11486.3 11488.4 11503.3 11515.4 4385.0 4687.3 4703.6 4734.9 4741.6 9682.9 9857.9 9861.1 9878.3 9879.6 5183.2 5183.9 5190.1 5191.8 5192.2 4553.2 4696.6 4733.4 4740.3 4754.8 3630.4 3664.6 3668.3 3753.5 3762.9 1123.3 1126.1 1134.9 1138.7 1141.1 797.5 837.7 884.4 889.9 931.3 5192.5 5653.4 5730.4 5902.7 6135.9 817.2 958.9 986.5 1016.5 1021.9 3647.1 3878.0 3909.0 3966.8 3969.9 8996.9 10356.1 10696.3 10700.5 10727.1 10557.6 10666.3 10668.5 10672.1 10687.9 7617.4 8053.0 8075.2 8170.5 8199.0 5400.4 5664.9 6103.5 6111.7 6135.1 9340.8 9417.9 9851.3 9924.1 10089.5 8958.3 9163.8 9924.0 9928.0 10284.4 6876.7 7300.1 7498.8 7538.7 7618.8 8084.0 8110.3 8114.7 8176.9 8220.1 7413.4 7467.7 7488.2 7530.8 7550.1 7047.4 7237.3 7239.1 7307.1 7386.8 6690.2 6820.6 6829.9 6831.5 6833.8 4898.4 4982.7 5046.9 5186.7 5195.4 5063.9 5076.8 5096.1 5226.2 5394.6 12:32:43 INFO - PROCESS | 1881 | 12:32:43 INFO - PROCESS | 1881 | [#4] prototype.html Cycles:180 Average:4261.05 Median:4786.65 stddev:1321.17 (27.6%) stddev-sans-first:1290.88 12:32:43 INFO - PROCESS | 1881 | Values: 293.6 301.1 303.4 308.2 308.3 5031.9 5039.5 5040.4 5061.6 5080.1 4095.4 4363.4 4750.4 5005.7 5018.1 3660.7 3694.4 3814.0 3841.4 3859.9 2980.4 3312.0 3876.0 3878.5 3909.8 3541.9 3563.5 3834.7 3859.8 3860.9 2922.9 3499.4 3636.8 3844.1 3849.8 3316.8 3361.7 3369.5 3371.9 3401.4 4021.9 4882.0 4902.7 4930.3 4948.7 3965.2 3968.3 4159.7 4386.8 4438.4 5073.1 5205.2 5230.9 5254.9 5268.3 3679.8 3701.5 3725.3 3725.9 3736.9 5293.5 5562.3 5576.3 5589.5 5604.7 3416.3 3636.8 3665.6 3704.5 3792.7 3725.9 3729.1 3730.9 3748.9 3767.0 4344.2 4362.6 4407.1 4408.6 4409.3 4473.8 5248.9 5331.5 5436.8 5456.7 1197.6 1268.2 1281.7 1287.4 1290.2 640.6 659.7 788.3 788.9 792.8 3771.6 4001.7 4118.4 4161.3 4843.4 4399.8 4513.9 5286.3 5294.9 5323.9 4175.4 4722.7 5015.4 5023.8 5030.4 2442.0 2468.3 2716.3 2765.8 2866.9 4917.0 5617.9 5657.4 5666.6 5674.9 5333.0 5459.6 5467.9 5545.3 5554.8 4362.7 5586.9 5640.9 5653.3 5682.2 4323.5 4724.6 5193.7 5410.9 5452.5 5002.5 5331.5 5365.6 5372.0 5380.8 5183.9 5185.0 5570.8 5579.4 5615.6 3642.0 3648.9 3651.6 3674.6 3696.5 4671.5 5043.8 5116.0 5349.4 5472.3 5299.7 5300.2 5307.9 5315.9 5431.2 4304.7 4672.5 5270.4 5284.3 5294.6 4822.9 5200.1 5207.4 5221.6 5251.4 4855.9 5352.8 5354.8 5366.4 5367.6 4262.7 4696.9 5115.1 5146.1 5175.9 12:32:43 INFO - PROCESS | 1881 | 12:32:43 INFO - PROCESS | 1881 | [#5] yui.html Cycles:180 Average:24670.12 Median:3597.69 stddev:40278.09 (1119.6%) stddev-sans-first:40350.85 12:32:43 INFO - PROCESS | 1881 | Values: 693.9 729.2 749.4 785.9 835.3 13513.9 13653.6 14210.4 14211.1 14328.6 12852.4 12960.2 12997.2 13030.4 13053.7 658.4 825.3 851.6 853.0 853.1 676.4 676.5 677.1 677.2 827.1 893.4 896.9 898.3 899.8 901.3 802.9 882.5 890.8 894.5 894.8 515.9 532.8 661.2 663.5 670.5 13109.1 13589.8 13637.0 13990.6 14003.7 14174.9 14583.5 14602.7 14730.0 14767.5 9648.0 11515.4 11639.6 11668.4 12012.0 3682.0 3723.1 3735.3 3738.3 3768.2 110955.0 111726.4 112032.0 114859.4 115834.3 10573.7 10793.7 10866.3 10957.5 11291.8 22613.0 22866.4 22912.4 23073.8 23267.0 7385.3 7393.1 7457.6 7485.9 7511.8 4111.4 4198.6 4248.2 4333.6 4474.8 3447.0 3588.2 3591.3 3593.5 3601.9 649.5 658.2 661.6 664.1 672.6 529.5 568.4 653.9 660.5 671.9 8250.8 9182.4 9195.3 9215.2 9495.2 601.8 630.4 656.5 656.7 660.2 4491.8 4549.8 4555.5 4567.5 4610.8 95218.5 95586.0 149656.0 161462.2 162764.0 139934.0 144609.0 146799.8 147527.3 149105.0 85162.7 105897.5 108752.0 109826.5 111989.4 8166.0 8393.2 8658.4 8820.3 8961.6 57740.4 67353.0 68972.0 69159.3 71192.6 61472.0 96267.0 102299.5 104308.5 104461.0 11090.5 11331.9 11348.9 11689.3 11743.4 12332.9 12417.6 12597.6 12774.0 13005.9 11652.3 11894.2 12315.7 12438.8 12470.3 10919.3 11836.8 12201.3 13684.0 14022.6 12885.2 12889.6 13150.2 13162.2 13297.9 10410.5 10988.4 11091.1 11435.8 12023.5 8557.7 9158.9 11293.3 11369.9 11471.1 12:32:43 INFO - PROCESS | 1881 | -------- Summary: end -------- 12:32:43 INFO - PROCESS | 1881 | 12:32:44 INFO - TEST-INFO | 1881: exit 0 12:32:44 INFO - TEST-OK | dromaeo_css | took 1118973ms 12:32:44 INFO - TEST-START | kraken 12:32:44 INFO - operating with platform_type : mac_ 12:32:44 INFO - Initialising browser for kraken test... 12:32:44 INFO - TEST-INFO | started process 1889 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmp5Ejxbj/profile http://localhost:49278/getInfo.html) 12:32:46 INFO - PROCESS | 1889 | __metrics Screen width/height:1600/1200 12:32:46 INFO - PROCESS | 1889 | colorDepth:24 12:32:46 INFO - PROCESS | 1889 | Browser inner width/height: 1024/689 12:32:46 INFO - PROCESS | 1889 | __metrics 12:32:46 INFO - PROCESS | 1889 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:32:46 INFO - PROCESS | 1889 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:32:47 INFO - TEST-INFO | 1889: exit 0 12:32:47 INFO - Browser initialized. 12:32:47 INFO - Running cycle 1/1 for kraken test... 12:32:47 INFO - TEST-INFO | started process 1890 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmp5Ejxbj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:32:59 INFO - PROCESS | 1890 | RSS: Main: 253804544 12:32:59 INFO - PROCESS | 1890 | 12:33:02 INFO - PROCESS | 1890 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 12:33:03 INFO - PROCESS | 1890 | RSS: Main: 310018048 12:33:03 INFO - PROCESS | 1890 | 12:33:05 INFO - PROCESS | 1890 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 12:33:06 INFO - PROCESS | 1890 | RSS: Main: 311275520 12:33:06 INFO - PROCESS | 1890 | 12:33:09 INFO - PROCESS | 1890 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 12:33:10 INFO - PROCESS | 1890 | RSS: Main: 399966208 12:33:10 INFO - PROCESS | 1890 | 12:33:12 INFO - PROCESS | 1890 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 12:33:13 INFO - PROCESS | 1890 | RSS: Main: 301256704 12:33:13 INFO - PROCESS | 1890 | 12:33:15 INFO - PROCESS | 1890 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:33:15 INFO - PROCESS | 1890 | RSS: Main: 302747648 12:33:15 INFO - PROCESS | 1890 | 12:33:18 INFO - PROCESS | 1890 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 12:33:19 INFO - PROCESS | 1890 | RSS: Main: 341889024 12:33:19 INFO - PROCESS | 1890 | 12:33:22 INFO - PROCESS | 1890 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 12:33:22 INFO - PROCESS | 1890 | RSS: Main: 342257664 12:33:22 INFO - PROCESS | 1890 | 12:33:25 INFO - PROCESS | 1890 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 12:33:26 INFO - PROCESS | 1890 | RSS: Main: 341041152 12:33:26 INFO - PROCESS | 1890 | 12:33:27 INFO - PROCESS | 1890 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:33:28 INFO - PROCESS | 1890 | RSS: Main: 314245120 12:33:28 INFO - PROCESS | 1890 | 12:33:30 INFO - PROCESS | 1890 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:33:30 INFO - PROCESS | 1890 | RSS: Main: 319959040 12:33:30 INFO - PROCESS | 1890 | 12:33:33 INFO - PROCESS | 1890 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:33:33 INFO - PROCESS | 1890 | RSS: Main: 322125824 12:33:33 INFO - PROCESS | 1890 | 12:33:36 INFO - PROCESS | 1890 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:33:37 INFO - PROCESS | 1890 | RSS: Main: 331022336 12:33:37 INFO - PROCESS | 1890 | 12:33:40 INFO - PROCESS | 1890 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:33:41 INFO - PROCESS | 1890 | RSS: Main: 331206656 12:33:41 INFO - PROCESS | 1890 | 12:33:42 INFO - PROCESS | 1890 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 12:33:43 INFO - PROCESS | 1890 | RSS: Main: 375173120 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | __start_tp_report 12:33:43 INFO - PROCESS | 1890 | _x_x_mozilla_page_load 12:33:43 INFO - PROCESS | 1890 | _x_x_mozilla_page_load_details 12:33:43 INFO - PROCESS | 1890 | |i|pagename|runs| 12:33:43 INFO - PROCESS | 1890 | |0;ai-astar;112;114;113;113;114;113;114;113;112;112 12:33:43 INFO - PROCESS | 1890 | |1;audio-beat-detection;147;112;112;150;114;113;116;140;170;109 12:33:43 INFO - PROCESS | 1890 | |2;audio-dft;183;182;179;185;185;181;182;212;178;181 12:33:43 INFO - PROCESS | 1890 | |3;audio-fft;81;74;82;112;61;60;58;60;83;70 12:33:43 INFO - PROCESS | 1890 | |4;audio-oscillator;76;95;128;71;68;69;92;66;69;90 12:33:43 INFO - PROCESS | 1890 | |5;imaging-gaussian-blur;88;85;88;85;87;84;88;86;88;84 12:33:43 INFO - PROCESS | 1890 | |6;imaging-darkroom;94;105;150;93;93;95;94;102;93;124 12:33:43 INFO - PROCESS | 1890 | |7;imaging-desaturate;85;85;87;84;85;84;85;86;86;100 12:33:43 INFO - PROCESS | 1890 | |8;json-parse-financial;56;54;53;53;54;55;55;54;55;55 12:33:43 INFO - PROCESS | 1890 | |9;json-stringify-tinderbox;67;78;66;77;73;71;70;71;71;72 12:33:43 INFO - PROCESS | 1890 | |10;stanford-crypto-aes;87;89;83;100;105;85;85;88;87;86 12:33:43 INFO - PROCESS | 1890 | |11;stanford-crypto-ccm;155;159;150;144;144;177;154;155;195;144 12:33:43 INFO - PROCESS | 1890 | |12;stanford-crypto-pbkdf2;169;172;170;221;234;172;170;172;170;169 12:33:43 INFO - PROCESS | 1890 | |13;stanford-crypto-sha256-iterative;69;69;68;67;67;69;68;68;68;66 12:33:43 INFO - PROCESS | 1890 | __end_tp_report 12:33:43 INFO - PROCESS | 1890 | __start_cc_report 12:33:43 INFO - PROCESS | 1890 | _x_x_mozilla_cycle_collect,2012 12:33:43 INFO - PROCESS | 1890 | __end_cc_report 12:33:43 INFO - PROCESS | 1890 | __startTimestamp1476387223212__endTimestamp 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | ------- Summary: start ------- 12:33:43 INFO - PROCESS | 1890 | Number of tests: 14 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.00 stddev:0.82 (0.7%) stddev-sans-first:0.78 12:33:43 INFO - PROCESS | 1890 | Values: 112.0 114.0 113.0 113.0 114.0 113.0 114.0 113.0 112.0 112.0 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:128.00 stddev:21.58 (16.9%) stddev-sans-first:21.80 12:33:43 INFO - PROCESS | 1890 | Values: 147.0 112.0 112.0 150.0 114.0 113.0 116.0 140.0 170.0 109.0 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | [#2] audio-dft Cycles:10 Average:184.80 Median:182.50 stddev:9.82 (5.4%) stddev-sans-first:10.39 12:33:43 INFO - PROCESS | 1890 | Values: 183.0 182.0 179.0 185.0 185.0 181.0 182.0 212.0 178.0 181.0 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | [#3] audio-fft Cycles:10 Average:74.10 Median:72.00 stddev:16.57 (23.0%) stddev-sans-first:17.39 12:33:43 INFO - PROCESS | 1890 | Values: 81.0 74.0 82.0 112.0 61.0 60.0 58.0 60.0 83.0 70.0 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | [#4] audio-oscillator Cycles:10 Average:82.40 Median:73.50 stddev:19.42 (26.4%) stddev-sans-first:20.46 12:33:43 INFO - PROCESS | 1890 | Values: 76.0 95.0 128.0 71.0 68.0 69.0 92.0 66.0 69.0 90.0 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.50 stddev:1.70 (1.9%) stddev-sans-first:1.69 12:33:43 INFO - PROCESS | 1890 | Values: 88.0 85.0 88.0 85.0 87.0 84.0 88.0 86.0 88.0 84.0 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | [#6] imaging-darkroom Cycles:10 Average:104.30 Median:93.00 stddev:18.75 (20.2%) stddev-sans-first:19.51 12:33:43 INFO - PROCESS | 1890 | Values: 94.0 105.0 150.0 93.0 93.0 95.0 94.0 102.0 93.0 124.0 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | [#7] imaging-desaturate Cycles:10 Average:86.70 Median:85.00 stddev:4.76 (5.6%) stddev-sans-first:5.01 12:33:43 INFO - PROCESS | 1890 | Values: 85.0 85.0 87.0 84.0 85.0 84.0 85.0 86.0 86.0 100.0 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:0.97 (1.8%) stddev-sans-first:0.83 12:33:43 INFO - PROCESS | 1890 | Values: 56.0 54.0 53.0 53.0 54.0 55.0 55.0 54.0 55.0 55.0 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:71.50 stddev:3.78 (5.3%) stddev-sans-first:3.62 12:33:43 INFO - PROCESS | 1890 | Values: 67.0 78.0 66.0 77.0 73.0 71.0 70.0 71.0 71.0 72.0 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:86.50 stddev:7.15 (8.3%) stddev-sans-first:7.53 12:33:43 INFO - PROCESS | 1890 | Values: 87.0 89.0 83.0 100.0 105.0 85.0 85.0 88.0 87.0 86.0 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | [#11] stanford-crypto-ccm Cycles:10 Average:157.70 Median:155.00 stddev:16.38 (10.6%) stddev-sans-first:17.35 12:33:43 INFO - PROCESS | 1890 | Values: 155.0 159.0 150.0 144.0 144.0 177.0 154.0 155.0 195.0 144.0 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.90 Median:172.00 stddev:24.26 (14.1%) stddev-sans-first:25.27 12:33:43 INFO - PROCESS | 1890 | Values: 169.0 172.0 170.0 221.0 234.0 172.0 170.0 172.0 170.0 169.0 12:33:43 INFO - PROCESS | 1890 | 12:33:43 INFO - PROCESS | 1890 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:0.97 12:33:43 INFO - PROCESS | 1890 | Values: 69.0 69.0 68.0 67.0 67.0 69.0 68.0 68.0 68.0 66.0 12:33:43 INFO - PROCESS | 1890 | -------- Summary: end -------- 12:33:43 INFO - PROCESS | 1890 | 12:33:44 INFO - TEST-INFO | 1890: exit 0 12:33:44 INFO - TEST-OK | kraken | took 59978ms 12:33:44 INFO - SUITE-END | took 1178s 12:33:44 INFO - Completed test suite (00:19:39) 12:33:44 INFO - javascript benchmark 12:33:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3314.188023934136, 3319.601647802264, 3354.563906692128, 3366.394049071166, 3392.6776956189155, 5440.891182176354, 5553.777848886045, 5564.888702225953, 5644.74598643061, 5661.886762264752, 5581.079121944885, 5584.692841893694, 5646.632968857021, 5670.376258611549, 5701.0, 5024.673396229252, 5064.290999260106, 5072.8731781705565, 5124.948750512484, 5137.922931156036, 5132.640715149941, 5220.686758794465, 5232.45059268777, 5235.633505654606, 5285.973570132144, 5031.84904452867, 5194.402643695986, 5203.973980130113, 5222.242774797652, 5283.313169287987, 5079.568236699885, 5144.871378215537, 5149.150390185615, 5152.175651895716, 5161.4322424533275, 5035.132870184405, 5112.514311140436, 5113.258577506278, 5124.769385377667, 5127.589792816603, 4849.884526558876, 5559.027170245191, 5575.275214222166, 5576.079946808813, 5599.524040456566, 5788.131780232999, 5977.491913187384, 5987.550933679991, 5992.161097446317, 5998.40015998401, 6072.848178795522, 6093.0, 6115.571909966304, 6122.693865306717, 6127.142200091991, 5579.944200557983, 5623.606347555632, 5652.293463317044, 5657.688827114507, 5691.4877661010705, 6326.145970294044, 6341.682915854189, 6344.68276586178, 6349.079383489368, 6359.968200159062, 5476.205950137208, 5946.85132871673, 5977.402259773988, 5983.311919129269, 5983.461488466059, 5781.392953739797, 5897.085951677497, 5984.281886173687, 5990.341062483123, 6157.9384206157365, 6044.123602077674, 6089.668886651826, 6098.9049174467955, 6102.51954821894, 6110.59976616339, 5068.949310506921, 6119.357467465941, 6127.172831667668, 6140.263168419729, 6143.32423433422, 4740.075685241345, 4793.640476964241, 4851.660383773173, 4852.636052296021, 4862.95137048625, 4305.526392096866, 4306.332518459643, 4351.6301114405, 4352.455943007124, 4406.823727050882, 5672.858178545569, 5674.801381951612, 5689.260396148474, 5691.516221121168, 5693.829185124453, 6179.351168127282, 6187.010078387437, 6189.566730328834, 6206.348333425016, 6226.533010024266, 5522.641028333145, 5544.1960915667005, 5590.776368945278, 5615.989121958005, 5657.971710141423, 5271.998320319127, 5314.176302673091, 5340.652857564246, 5349.304590403222, 5370.4361042090795, 6309.621422714652, 6323.9051414228825, 6351.968240158954, 6362.809115726351, 6365.777197798055, 5964.522838173022, 6088.0, 6214.689265536796, 6217.502599791916, 6223.253209614875, 6295.150154729053, 6325.96837015812, 6334.176557047738, 6339.96830015847, 6346.048092786119, 6046.365131661112, 6058.7273572688455, 6079.756809727738, 6087.443779412026, 6108.08378743192, 6098.634081955097, 6160.767846430642, 6163.938360616516, 6200.038993955944, 6213.440790328892, 4852.781624826821, 4909.459959404534, 5677.0, 5737.540996720169, 5925.022371308686, 5948.048312270189, 5948.40515948419, 5952.285725712942, 5954.702264886825, 5995.220621319375, 5803.593748437569, 5807.274090738658, 5818.563607729353, 5820.70896455184, 5826.446487583673, 5741.885162296647, 5828.388019258086, 5831.183634291117, 5863.355030946678, 5871.6183448075735, 5747.8563035924435, 5797.913031304618, 5817.360090390139, 5850.53195744348, 5861.7655293787775], "value": 5580.768653037385, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.3517710382568, 485.7012937043719, 496.52581784395903, 539.325842696629, 552.61869310176, 31053.534196987035, 39991.40012899805, 40999.18001639965, 41020.99999999996, 43301.35047974283, 42905.0, 43249.7837510812, 43289.56710432895, 43290.13419731604, 43345.6996290111, 9520.857187142181, 9717.562709678046, 9723.222142228604, 9729.902700973005, 9744.269179811507, 7703.653335599884, 8765.211130998208, 11651.0, 11661.358625275607, 11952.163348565604, 11835.704107397298, 11878.8715072068, 11894.524219031271, 11920.642380728592, 11994.100442466806, 11733.413329333542, 11820.822687659693, 11856.288622682581, 11859.110566707444, 11863.881361186364, 11555.422228888607, 11570.78506756787, 11632.883671163265, 11633.825492617618, 11640.825387619192, 40427.59572404297, 40794.184116317505, 40803.0, 40838.0, 40874.591254087376, 3189.8086114832954, 3204.3591281743743, 3301.6533264007176, 3654.451832225161, 4021.2761702893467, 30504.237394065105, 31146.0, 31722.206944826332, 32418.6758132418, 32428.0, 3199.680031996805, 3234.9518039929676, 3288.19439237386, 3375.240570871534, 3389.881354152593, 475874.24125758646, 477088.0, 478114.0, 478799.0, 488595.55702221615, 51076.74461627668, 61399.77200455966, 61970.69014654898, 61995.07007394802, 62543.0, 62528.0, 64528.35471645317, 64975.0, 65054.34945650483, 65105.67447162734, 15375.308111135026, 15641.452549160953, 15653.90422670425, 15676.05943643385, 15680.529584112495, 10790.352578845295, 11032.172587056002, 11549.88450115488, 11860.288382697066, 12029.879701203052, 15647.374105035899, 16234.918825405797, 16352.100634465218, 18850.24599016024, 19302.4209273722, 7757.301842834172, 7790.844183116419, 7826.8434631307055, 7851.214878512217, 7980.481268717631, 815.8653822119284, 856.7087190355309, 933.4737703854112, 953.7043516509904, 1058.6347710040025, 27116.728832711815, 30354.84822575873, 30743.385132297677, 31539.0, 31930.361392772014, 899.6671231644334, 962.2013728605381, 1024.472396715692, 1030.3611760708259, 1094.4582431696201, 15023.248837558296, 15451.84548154504, 15671.764923526156, 15722.528324150293, 15901.363945442054, 562165.0, 662739.0, 663767.0, 664369.6781516061, 665372.6731366313, 57158.7142064287, 57529.712351438815, 57690.13464798031, 57782.7110864443, 57796.71101644465, 1214.993925030387, 1248.8876001159758, 1310.6919873829643, 1341.5170538606094, 1366.214426704629, 29972.100836974925, 30589.23526911845, 31257.0, 31472.213194670316, 32172.83913580417, 50148.74925625348, 55803.0, 57114.7144264276, 57134.42865571291, 59300.0, 531747.0, 598522.0, 602137.0, 603973.0, 604387.0, 26400.811963461307, 27562.862185688944, 27955.580666289614, 28218.71781282243, 42733.78633106814, 46378.84052898704, 46984.29523557081, 47742.80642983953, 48103.518964809904, 48633.51366486432, 39466.605333946296, 39479.0, 39583.0, 39828.2034359317, 41090.58909410987, 39375.40936885892, 39984.600153998086, 40235.59764402318, 40639.0, 40868.79565602272, 38612.80693596514, 39267.21465570616, 39305.0, 39449.0, 39562.40656390214, 32257.0, 34148.0, 34506.82746586251, 34657.65342346544, 35011.12472188216, 22947.6557851629, 25493.745062549136, 25546.339487933226, 25683.87158064198, 26376.472470550867], "value": 20470.095165050676, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.6315000824139, 371.5597017534222, 372.56410000299627, 380.63839352615025, 402.436588775714, 14911.627209319759, 14991.676290175026, 15631.687366252683, 19850.007499625033, 20744.066517006733, 19182.712259316086, 19429.31997380092, 20060.799392006076, 20188.495287617818, 20339.28812491563, 5553.500184983351, 5846.298444186704, 5859.589828711992, 5859.736311865976, 5861.325947516048, 5013.699178049329, 6374.776882809103, 6453.677316134198, 6495.415412612864, 6522.282548919615, 6123.295820980599, 6316.715747791361, 6420.7527365421965, 6445.097686323918, 6455.515836312272, 6327.873442531123, 6363.936360636404, 6421.5825971311715, 6428.485721142298, 6435.163428754256, 5397.325334333208, 5518.668879867221, 5519.503244707956, 5561.082421400464, 5575.581831362623, 23980.52038959211, 24444.633330500055, 24986.750132498622, 25213.87393063023, 25226.621600676004, 2167.8157356624547, 2281.600719874032, 2349.7180338359335, 2599.324175714309, 2759.0619189475474, 14751.40994360235, 15031.473898413504, 15384.384624615002, 15529.145896975662, 15610.29753661088, 1702.8978261304362, 1735.722284434497, 1763.9823601763946, 1806.3406856497415, 1900.0594705620667, 87728.684069739, 112371.0, 112802.87197128005, 114611.28083078761, 115374.0, 8110.0, 9535.666251681158, 9608.327417080876, 10127.291089623655, 10352.0, 12127.272363658209, 12137.635870923887, 12215.938920305342, 12235.877641223651, 12280.140390172603, 7585.469017168855, 7618.6190690465255, 7653.540787552764, 7792.8051798704355, 7798.961005195051, 7893.802654933673, 7925.8811117833275, 7972.760817175378, 8109.0, 8162.102168761431, 3689.7970611616083, 3758.1544152566003, 3759.7180211484247, 3813.56144043433, 3815.847366105325, 1840.88954662718, 1876.7372567840514, 1883.4949517142168, 1895.687211610069, 1895.6966885298286, 839.3536976528161, 840.7015509494041, 912.7626817027488, 934.4159900062461, 965.7054598347431, 20060.89969550143, 20721.792782072287, 20739.585208296052, 20834.47913802137, 21023.95425097996, 871.7036207689416, 883.779055236191, 896.1665650944684, 911.3574929674563, 1022.8363461846218, 8512.74461766148, 10309.639162629423, 10922.344659320308, 11026.393548354918, 11051.447427628586, 463204.0, 479036.0, 484796.0, 581985.0, 583589.0, 77970.61014694891, 95896.52051739697, 106041.0, 120217.79782202066, 140413.0, 938.6245501799335, 965.6234068713208, 992.2706810494425, 1003.9949800250952, 1017.7455636090978, 17781.1998460067, 18080.72878906844, 18525.258989640264, 18713.877167370003, 20215.0, 44056.559434405244, 58330.0, 58812.0, 59172.22483325507, 59187.81624367575, 60813.087803682094, 80745.0, 81608.0, 82015.5899220524, 82813.0, 5703.572232082527, 7616.857471379338, 8074.636641351036, 8237.83524329522, 8438.746837594881, 11946.76106477883, 12455.501779928702, 12903.354832258161, 12997.415116319598, 13132.540361087316, 9933.00669933001, 10656.467176641292, 10863.945680271549, 11261.324320540794, 11432.885671143182, 10834.620788272372, 11159.38623375722, 11185.328880266885, 11516.827247591125, 11606.071514278668, 8475.618597163266, 10347.0, 10851.728706782395, 10899.836502452628, 11474.426278685865, 7743.303102720557, 7892.250236227549, 8033.5983200838555, 8180.222878826502, 8318.209770071833, 8184.7953801155445, 10568.577256909637, 10987.340759554452, 11383.260088094248, 11468.311901285615], "value": 9310.763546149197, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1492.9029613075145, 1500.6923580665964, 1680.722680757675, 1812.4019073705679, 1859.8047205043479, 8291.58542072897, 8815.691450799224, 8828.984666763325, 8843.0, 8846.292296616264, 7786.1435242123325, 8042.437029407944, 8042.839143217147, 8361.414700970934, 8642.611082501287, 2291.5302363015626, 2329.301209637102, 2330.149495434171, 2331.8600883946933, 2336.2290444153386, 2311.0986715181093, 2316.895739691718, 2343.695319608457, 2354.6585745066955, 2357.56385068762, 2296.9885150574223, 2299.839011269212, 2300.884955752214, 2307.5038866643667, 2355.0462062864567, 1933.2073849721546, 2308.4459729664845, 2319.0375993962653, 2321.93034208974, 2322.698049253603, 2296.4258935266184, 2320.6054970655027, 2322.8606283622867, 2324.0819876148994, 2327.6392159215347, 8531.677589973564, 8711.782205444915, 8752.912470875273, 8828.690995815115, 8835.381523293372, 4496.752678602676, 5220.8694782630355, 5228.215767634847, 5230.947690523084, 5235.55497782689, 3247.2044349134376, 3273.854151047138, 3332.0836769888233, 3442.122258823984, 3543.787372757643, 955.8911662471569, 1065.4672663668166, 1066.9626563070333, 1067.765091679837, 1091.7052395853154, 11458.94270528642, 11486.310821350662, 11488.36813975231, 11503.309801411942, 11515.36665483398, 4384.956150438518, 4687.343771871941, 4703.576678098988, 4734.881627959328, 4741.644376671695, 9682.854757178648, 9857.901420985841, 9861.063198996084, 9878.259130565237, 9879.579503021661, 5183.248428977777, 5183.948160518422, 5190.143626301618, 5191.8182863599595, 5192.221166824931, 4553.248713962225, 4696.577308042292, 4733.384659994179, 4740.26525888481, 4754.809807607726, 3630.41913293877, 3664.5602527696847, 3668.2663467306647, 3753.530808648919, 3762.887113386603, 1123.309164863603, 1126.1159989408275, 1134.931904085741, 1138.703936976392, 1141.0928311991986, 797.5175019113353, 837.660747397305, 884.3544212724747, 889.8698652711021, 931.2922179143764, 5192.480751924777, 5653.434656534396, 5730.426957304237, 5902.704864756745, 6135.877282454415, 817.1828171828172, 958.8561715742555, 986.496886587831, 1016.4612755239586, 1021.933574317667, 3647.124690074363, 3877.9806100969313, 3908.9609103909165, 3966.781826999485, 3969.940450893181, 8996.8650470243, 10356.119729823049, 10696.251262411557, 10700.518476668569, 10727.088197503144, 10557.577696892193, 10666.306690065121, 10668.466576671135, 10672.146228301717, 10687.946560267304, 7617.352525035434, 8052.959735201403, 8075.232852878967, 8170.468919520212, 8199.01611806575, 5400.4059553448305, 5664.943350566442, 6103.542234332532, 6111.663858487826, 6135.141080248858, 9340.81318373615, 9417.916939552038, 9851.261155413229, 9924.1068303853, 10089.545970431494, 8958.328125390492, 9163.816723665623, 9924.0, 9928.0, 10284.434356110485, 6876.69054892541, 7300.087489063867, 7498.775036748906, 7538.6607602659105, 7618.847623047619, 8084.0, 8110.270075693216, 8114.675412983414, 8176.877346839921, 8220.095789463036, 7413.369863561657, 7467.738629147953, 7488.213737557478, 7530.774076777706, 7550.131734850563, 7047.436205103682, 7237.312455316736, 7239.095113110861, 7307.050083489113, 7386.815329616803, 6690.19717633887, 6820.62486563224, 6829.863402732017, 6831.521793474608, 6833.760818371333, 4898.387701537308, 4982.7259500727805, 5046.899062018812, 5186.662866913639, 5195.350581177353, 5063.924041139459, 5076.771545280398, 5096.0827051131155, 5226.163813789927, 5394.56843452515], "value": 4504.778011571139, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.58457782238133, 301.1132215624984, 303.41592434563466, 308.18638793584927, 308.3232305090328, 5031.874203144918, 5039.470855560168, 5040.37900291848, 5061.569766569842, 5080.110980578403, 4095.385692146172, 4363.410939523172, 4750.429948406196, 5005.6746311489815, 5018.146915024446, 3660.6888414484733, 3694.390425579776, 3814.0, 3841.3661745811905, 3859.8842034739005, 2980.4188183304154, 3311.989843097854, 3875.961240387602, 3878.5151856017997, 3909.76541407515, 3541.8937431876957, 3563.536740223767, 3834.6740527055094, 3859.787711675871, 3860.9227815443674, 2922.8976985805393, 3499.4400895856797, 3636.799976001319, 3844.136118591639, 3849.826757795906, 3316.817575033372, 3361.714254288364, 3369.5451114099774, 3371.865125394981, 3401.4047541680357, 4021.879343619696, 4881.951180488185, 4902.681325713817, 4930.260460930853, 4948.653594248404, 3965.246603145422, 3968.3055465293746, 4159.729617574848, 4386.758728269944, 4438.4451943507065, 5073.121382983448, 5205.245239440298, 5230.895382092375, 5254.8686282842855, 5268.315119034501, 3679.8344074516713, 3701.5188025556504, 3725.2735716535285, 3725.8882233533036, 3736.8505259789576, 5293.470652934714, 5562.3047119110115, 5576.275084239023, 5589.540602135047, 5604.747786349583, 3416.299658570037, 3636.781793092423, 3665.5601327840295, 3704.5184126063978, 3792.7345085843726, 3725.869594564177, 3729.1422972716427, 3730.8880733578035, 3748.850045998184, 3766.981165094157, 4344.23975804233, 4362.578768783355, 4407.096545208225, 4408.58118478744, 4409.2724700424715, 4473.7986790594505, 5248.921266181011, 5331.466853314638, 5436.809711660152, 5456.727163641882, 1197.550918405598, 1268.1820225954207, 1281.7436512697498, 1287.3949243855373, 1290.2258644813037, 640.6380395076767, 659.6866488417963, 788.333857890082, 788.8974433323746, 792.8453951479521, 3771.585125636178, 4001.6786067564435, 4118.364246881998, 4161.313383291723, 4843.370361852936, 4399.824007039682, 4513.887152821206, 5286.273177409917, 5294.894102117936, 5323.893522129536, 4175.373693945932, 4722.716637001722, 5015.3730783652045, 5023.773930173151, 5030.3963524377305, 2441.9865755711535, 2468.321211666806, 2716.293763621473, 2765.783055455593, 2866.942661146807, 4917.041176970456, 5617.859553511195, 5657.434256574392, 5666.575006874501, 5674.886502269931, 5333.040052790458, 5459.590530710213, 5467.922189567938, 5545.334559852763, 5554.805716770914, 4362.716423432466, 5586.86032849182, 5640.943590564042, 5653.265075540236, 5682.232898558725, 4323.54602766705, 4724.598409135262, 5193.662411943212, 5410.891782164414, 5452.454754524674, 5002.474245355202, 5331.466853314638, 5365.624406291522, 5372.0, 5380.847660935657, 5183.922241166461, 5184.9740751296, 5570.805021824217, 5579.414161513145, 5615.568030152285, 3642.0, 3648.8540458381367, 3651.634836516327, 3674.6141655125825, 3696.4824924511126, 4671.509491503343, 5043.823466178666, 5115.974420127875, 5349.358077030625, 5472.343318801769, 5299.682019078868, 5300.151975684026, 5307.867303317448, 5315.893682126413, 5431.158170483582, 4304.720193187432, 4672.523562511874, 5270.4202537721585, 5284.339457567804, 5294.602904782079, 4822.879428014187, 5200.089984252816, 5207.401148867929, 5221.556167725786, 5251.36983561975, 4855.927161092657, 5352.812651557177, 5354.7858085676135, 5366.356037275552, 5367.570594352364, 4262.744235345889, 4696.92954605688, 5115.10741025808, 5146.0737067326745, 5175.896482070413], "value": 3837.8323830098752, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.9028536004959, 729.1833146875497, 749.4042236422041, 785.8821176823479, 835.3484282259838, 13513.864861351383, 13653.590392288235, 14210.360533775993, 14211.07628004179, 14328.641783955407, 12852.421641026152, 12960.15758975666, 12997.155184912974, 13030.413631386587, 13053.67365815855, 658.4436151452039, 825.2984962781633, 851.6040041380757, 852.9786755331104, 853.1425916953453, 676.3507033248088, 676.5365724478747, 677.0926957876444, 677.197520937688, 827.0902007791442, 893.3701740273092, 896.8968568614595, 898.3397203055749, 899.7615631857562, 901.2789768185459, 802.9036515618105, 882.5190271302156, 890.8173824365989, 894.4990805149072, 894.8344556257114, 515.9019786240602, 532.8028629407144, 661.2032500836528, 663.4625952978104, 670.4803777072759, 13109.082364234508, 13589.796153057712, 13637.0, 13990.580282591436, 14003.71992560143, 14174.858251417458, 14583.477873036261, 14602.707945841024, 14730.0, 14767.483138090223, 9648.0, 11515.399427322911, 11639.59261425846, 11668.35824029678, 12012.0, 3681.9631803682155, 3723.087843478338, 3735.34631439497, 3738.2710371477574, 3768.208676178006, 110955.0, 111726.44136779345, 112032.0, 114859.42570287095, 115834.26248606277, 10573.661481448251, 10793.730156746144, 10866.293690910066, 10957.50691218897, 11291.774164516828, 22613.0, 22866.42833929165, 22912.4271893204, 23073.769262307163, 23267.0, 7385.261473852572, 7393.149787774475, 7457.552546847207, 7485.925140748632, 7511.849763004819, 4111.424400583921, 4198.580141985838, 4248.171606536695, 4333.634905004977, 4474.7762611869275, 3447.0, 3588.1926566522325, 3591.2996965591983, 3593.5328407307406, 3601.8559257629927, 649.4706813946618, 658.2002866517205, 661.6096503063216, 664.0901964308831, 672.579637726421, 529.5350145074607, 568.3804652928328, 653.9247986481521, 660.5211221864206, 671.9059331693567, 8250.793730156674, 9182.449053056838, 9195.264378849675, 9215.170634642915, 9495.192908602847, 601.7983975368301, 630.3727790848136, 656.540421704799, 656.7143292667705, 660.1681880830189, 4491.8427855025575, 4549.772511374418, 4555.476120246148, 4567.474740404896, 4610.815567377334, 95218.52390738002, 95586.0, 149656.0, 161462.19268903814, 162764.0, 139934.0, 144609.0, 146799.79800303004, 147527.2623636875, 149105.0, 85162.72255916166, 105897.47051264695, 108752.0, 109826.45086774515, 111989.44137262103, 8166.0, 8393.160683931803, 8658.393912426322, 8820.25027872638, 8961.641534338554, 57740.422595775184, 67353.0, 68972.0, 69159.30840691528, 71192.64403678155, 61472.0, 96267.0, 102299.48850256, 104308.47845761027, 104461.0, 11090.500927458444, 11331.943340283246, 11348.886511134782, 11689.298642081503, 11743.354115523729, 12332.938335308267, 12417.565385211474, 12597.559085431965, 12774.0, 13005.934970325088, 11652.30086194831, 11894.226875253082, 12315.69210769738, 12438.751224975269, 12470.251784892935, 10919.344839309666, 11836.76326473483, 12201.267923924594, 13684.0, 14022.579322620337, 12885.162464439782, 12889.613311600668, 13150.21098734079, 13162.24710567462, 13297.867021329663, 10410.531526081193, 10988.395638239974, 11091.116309759682, 11435.828462572901, 12023.4589443477, 8557.74326770173, 9158.908410915805, 11293.265937714023, 11369.943150284196, 11471.139664525028], "value": 7548.51382838549, "unit": "score"}], "name": "dromaeo_css", "value": 7195.789428683659, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 113.0, 113.0, 114.0, 113.0, 114.0, 113.0, 112.0, 112.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 112.0, 112.0, 150.0, 114.0, 113.0, 116.0, 140.0, 170.0, 109.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 179.0, 185.0, 185.0, 181.0, 182.0, 212.0, 178.0, 181.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 74.0, 82.0, 112.0, 61.0, 60.0, 58.0, 60.0, 83.0, 70.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 95.0, 128.0, 71.0, 68.0, 69.0, 92.0, 66.0, 69.0, 90.0], "value": 82.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 87.0, 84.0, 88.0, 86.0, 88.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 105.0, 150.0, 93.0, 93.0, 95.0, 94.0, 102.0, 93.0, 124.0], "value": 104.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 84.0, 85.0, 84.0, 85.0, 86.0, 86.0, 100.0], "value": 86.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 53.0, 53.0, 54.0, 55.0, 55.0, 54.0, 55.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 78.0, 66.0, 77.0, 73.0, 71.0, 70.0, 71.0, 71.0, 72.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 83.0, 100.0, 105.0, 85.0, 85.0, 88.0, 87.0, 86.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 159.0, 150.0, 144.0, 144.0, 177.0, 154.0, 155.0, 195.0, 144.0], "value": 157.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 172.0, 170.0, 221.0, 234.0, 172.0, 170.0, 172.0, 170.0, 169.0], "value": 181.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 67.0, 67.0, 69.0, 68.0, 68.0, 68.0, 66.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1482.9000000000003, "alertThreshold": 2.0}]} 12:33:44 INFO - Return code: 0 12:33:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:33:44 INFO - # TBPL SUCCESS # 12:33:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:33:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:33:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:33:44 INFO - 2016-10-13 12:33:44.552 screenresolution[1894:10520] starting screenresolution argv=screenresolution get 12:33:44 INFO - 2016-10-13 12:33:44.554 screenresolution[1894:10520] Display 0: 1600x1200x32@60 12:33:44 INFO - 2016-10-13 12:33:44.560 screenresolution[1895:10523] starting screenresolution argv=screenresolution list 12:33:44 INFO - Available Modes on Display 0 12:33:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:33:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:33:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:33:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:33:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:33:44 INFO - Intel Iris: 12:33:44 INFO - Chipset Model: Intel Iris 12:33:44 INFO - Type: GPU 12:33:44 INFO - Bus: Built-In 12:33:44 INFO - VRAM (Dynamic, Max): 1536 MB 12:33:44 INFO - Vendor: Intel (0x8086) 12:33:44 INFO - Device ID: 0x0a2e 12:33:44 INFO - Revision ID: 0x0009 12:33:44 INFO - Displays: 12:33:44 INFO - Display: 12:33:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:33:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:33:44 INFO - Display Serial Number: 1600x1200 60 12:33:44 INFO - Main Display: Yes 12:33:44 INFO - Mirror: Off 12:33:44 INFO - Online: Yes 12:33:44 INFO - Rotation: Supported 12:33:44 INFO - Return code: 0 12:33:44 INFO - Running post-action listener: _resource_record_post_action 12:33:44 INFO - [mozharness: 2016-10-13 19:33:44.794426Z] Finished run-tests step (success) 12:33:44 INFO - Running post-run listener: _resource_record_post_run 12:33:44 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 32103424; Write bytes: 707847168; Read time: 199; Write time: 2002 12:33:44 INFO - TinderboxPrint: CPU usage
53.4% 12:33:44 INFO - TinderboxPrint: I/O read bytes / time
32,103,424 / 199 12:33:44 INFO - TinderboxPrint: I/O write bytes / time
707,847,168 / 2,002 12:33:44 INFO - TinderboxPrint: CPU idle
2,263.1 (46.6%) 12:33:44 INFO - TinderboxPrint: CPU system
970.5 (20.0%) 12:33:44 INFO - TinderboxPrint: CPU user
1,624.1 (33.4%) 12:33:44 INFO - TinderboxPrint: Swap in / out
435,326,976 / 0 12:33:44 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 393341440; Write bytes: 387315200; Read time: 29410; Write time: 831 12:33:44 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 31181824; Write bytes: 298208768; Read time: 187; Write time: 1115 12:33:45 INFO - Running post-run listener: _upload_blobber_files 12:33:45 INFO - Blob upload gear active. 12:33:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:33:45 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 12:33:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:33:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:33:45 INFO - (blobuploader) - INFO - Open directory for files ... 12:33:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:33:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:33:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:46 INFO - (blobuploader) - INFO - Done attempting. 12:33:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:33:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:33:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:47 INFO - (blobuploader) - INFO - Done attempting. 12:33:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:33:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:33:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:50 INFO - (blobuploader) - INFO - Done attempting. 12:33:50 INFO - (blobuploader) - INFO - Iteration through files over. 12:33:50 INFO - Return code: 0 12:33:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:33:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:33:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e01b8e7b546d60a30840ca7e852cedbd89fa53b0a2b3d016cdb1a0b187eff3275a15ddf3ebd12c9b96c84aa0bfd205909746afcd151bfed4760cd8f4e31065e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc3d82883a4a0437fc358184b3f4969119d64e6f2c13cc97456995b7f701851f6f89f75c3f379d69a7132f7c2b8e73882b7f31892b064bd1de58848be865ec13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fe9e5d0d2b8c0e07682152545cea20d67c487fd93d79a8dddec18ed893a3cc6488e8225cb9cb2d632df13137b0366a0ffdce275de29853eee410e8a99de23e1"} 12:33:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:33:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:33:50 INFO - Contents: 12:33:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e01b8e7b546d60a30840ca7e852cedbd89fa53b0a2b3d016cdb1a0b187eff3275a15ddf3ebd12c9b96c84aa0bfd205909746afcd151bfed4760cd8f4e31065e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc3d82883a4a0437fc358184b3f4969119d64e6f2c13cc97456995b7f701851f6f89f75c3f379d69a7132f7c2b8e73882b7f31892b064bd1de58848be865ec13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fe9e5d0d2b8c0e07682152545cea20d67c487fd93d79a8dddec18ed893a3cc6488e8225cb9cb2d632df13137b0366a0ffdce275de29853eee410e8a99de23e1"} 12:33:50 INFO - Running post-run listener: copy_logs_to_upload_dir 12:33:50 INFO - Copying logs to upload dir... 12:33:50 INFO - mkdir: /builds/slave/test/build/upload/logs 12:33:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.358025 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-13 12:33:50.350594) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 12:33:50.354242) ========= 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.gNkrkN0xoE/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.opYDuCxEK6/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e01b8e7b546d60a30840ca7e852cedbd89fa53b0a2b3d016cdb1a0b187eff3275a15ddf3ebd12c9b96c84aa0bfd205909746afcd151bfed4760cd8f4e31065e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc3d82883a4a0437fc358184b3f4969119d64e6f2c13cc97456995b7f701851f6f89f75c3f379d69a7132f7c2b8e73882b7f31892b064bd1de58848be865ec13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fe9e5d0d2b8c0e07682152545cea20d67c487fd93d79a8dddec18ed893a3cc6488e8225cb9cb2d632df13137b0366a0ffdce275de29853eee410e8a99de23e1"} build_url:https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010651 build_url: 'https://queue.taskcluster.net/v1/task/N3IZaU39Ryqv_rtH__kkxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e01b8e7b546d60a30840ca7e852cedbd89fa53b0a2b3d016cdb1a0b187eff3275a15ddf3ebd12c9b96c84aa0bfd205909746afcd151bfed4760cd8f4e31065e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc3d82883a4a0437fc358184b3f4969119d64e6f2c13cc97456995b7f701851f6f89f75c3f379d69a7132f7c2b8e73882b7f31892b064bd1de58848be865ec13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fe9e5d0d2b8c0e07682152545cea20d67c487fd93d79a8dddec18ed893a3cc6488e8225cb9cb2d632df13137b0366a0ffdce275de29853eee410e8a99de23e1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 12:33:50.387142) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:33:50.387493) ========= 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.gNkrkN0xoE/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.opYDuCxEK6/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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.004905 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:33:50.436825) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 12:33:50.437143) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 12:33:50.437454) ========= ========= Total master_lag: 0.17 =========