builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0324 starttime: 1476046710.58 results: success (0) buildid: 20161009120418 builduid: de130ea26c6a42cfbade47182c7ce474 revision: 0ba0016683aef98cd7a64ff5e31f23e8d7eb08aa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-09 13:58:30.584503) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-09 13:58:30.584970) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-09 13:58:30.585305) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-09 13:58:30.969629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 13:58:30.969921) ========= 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.9joIxGepQg/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.GRsJe4EG39/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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-09 13:58:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.8M=0.001s 2016-10-09 13:58:31 (18.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116544 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 13:58:31.190083) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 13:58:31.190409) ========= 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.9joIxGepQg/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.GRsJe4EG39/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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.026417 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 13:58:31.255962) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-09 13:58:31.256321) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0ba0016683aef98cd7a64ff5e31f23e8d7eb08aa --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 0ba0016683aef98cd7a64ff5e31f23e8d7eb08aa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9joIxGepQg/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.GRsJe4EG39/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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-09 13:58:31,345 truncating revision to first 12 chars 2016-10-09 13:58:31,345 Setting DEBUG logging. 2016-10-09 13:58:31,345 attempt 1/10 2016-10-09 13:58:31,346 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0ba0016683ae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-09 13:58:32,189 unpacking tar archive at: mozilla-central-0ba0016683ae/testing/mozharness/ program finished with exit code 0 elapsedTime=1.113809 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-09 13:58:32.397676) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-09 13:58:32.397988) ========= script_repo_revision: 0ba0016683aef98cd7a64ff5e31f23e8d7eb08aa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-09 13:58:32.398419) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-09 13:58:32.398722) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-09 13:58:32.417103) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-09 13:58:32.417458) ========= /tools/buildbot/bin/python 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', '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.9joIxGepQg/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.GRsJe4EG39/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 13:58:32 INFO - MultiFileLogger online at 20161009 13:58:32 in /builds/slave/test 13:58:32 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 13:58:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:58:32 INFO - {'append_to_log': False, 13:58:32 INFO - 'base_work_dir': '/builds/slave/test', 13:58:32 INFO - 'blob_upload_branch': 'Firefox', 13:58:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:58:32 INFO - 'branch': 'Firefox', 13:58:32 INFO - 'buildbot_json_path': 'buildprops.json', 13:58:32 INFO - 'config_files': ('talos/mac_config.py',), 13:58:32 INFO - 'default_actions': ('clobber', 13:58:32 INFO - 'read-buildbot-config', 13:58:32 INFO - 'download-and-extract', 13:58:32 INFO - 'populate-webroot', 13:58:32 INFO - 'create-virtualenv', 13:58:32 INFO - 'install', 13:58:32 INFO - 'run-tests'), 13:58:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:58:32 INFO - 'download_minidump_stackwalk': True, 13:58:32 INFO - 'download_symbols': 'ondemand', 13:58:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:58:32 INFO - 'tooltool.py': '/tools/tooltool.py', 13:58:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:58:32 INFO - '/tools/misc-python/virtualenv.py')}, 13:58:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:58:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:58:32 INFO - 'installer_path': 'installer.exe', 13:58:32 INFO - 'log_level': 'info', 13:58:32 INFO - 'log_name': 'talos', 13:58:32 INFO - 'log_to_console': True, 13:58:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:58:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:58:32 INFO - 'opt_config_files': (), 13:58:32 INFO - 'pip_index': False, 13:58:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:58:32 INFO - 'cmd': ('bash', 13:58:32 INFO - '-c', 13:58:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:58:32 INFO - 'enabled': True, 13:58:32 INFO - 'halt_on_failure': False, 13:58:32 INFO - 'name': 'check_screen_resolution'},), 13:58:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:58:32 INFO - 'cmd': ('bash', 13:58:32 INFO - '-c', 13:58:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:58:32 INFO - 'enabled': True, 13:58:32 INFO - 'halt_on_failure': False, 13:58:32 INFO - 'name': 'check_screen_resolution'},), 13:58:32 INFO - 'run_cmd_checks_enabled': True, 13:58:32 INFO - 'sps_profile': False, 13:58:32 INFO - 'sps_profile_interval': 0, 13:58:32 INFO - 'suite': 'dromaeojs', 13:58:32 INFO - 'system_bits': '32', 13:58:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:58:32 INFO - 'title': 't-yosemite-r7-0324', 13:58:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:58:32 INFO - 'use_talos_json': True, 13:58:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:58:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:58:32 INFO - 'work_dir': 'build'} 13:58:32 INFO - [mozharness: 2016-10-09 20:58:32.619102Z] Running clobber step. 13:58:32 INFO - Running pre-action listener: _resource_record_pre_action 13:58:32 INFO - Running main action method: clobber 13:58:32 INFO - rmtree: /builds/slave/test/build 13:58:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:58:34 INFO - Running post-action listener: _resource_record_post_action 13:58:34 INFO - [mozharness: 2016-10-09 20:58:34.811577Z] Finished clobber step (success) 13:58:34 INFO - [mozharness: 2016-10-09 20:58:34.811699Z] Running read-buildbot-config step. 13:58:34 INFO - Running pre-action listener: _resource_record_pre_action 13:58:34 INFO - Running main action method: read_buildbot_config 13:58:34 INFO - Using buildbot properties: 13:58:34 INFO - { 13:58:34 INFO - "project": "", 13:58:34 INFO - "product": "firefox", 13:58:34 INFO - "script_repo_revision": "production", 13:58:34 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 13:58:34 INFO - "repository": "", 13:58:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 13:58:34 INFO - "buildid": "20161009120418", 13:58:34 INFO - "pgo_build": "False", 13:58:34 INFO - "basedir": "/builds/slave/test", 13:58:34 INFO - "buildnumber": 5, 13:58:34 INFO - "slavename": "t-yosemite-r7-0324", 13:58:34 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:58:34 INFO - "platform": "yosemite_r7", 13:58:34 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 13:58:34 INFO - "branch": "mozilla-central", 13:58:34 INFO - "repo_path": "mozilla-central", 13:58:34 INFO - "revision": "0ba0016683aef98cd7a64ff5e31f23e8d7eb08aa", 13:58:34 INFO - "stage_platform": "macosx64", 13:58:34 INFO - "builduid": "de130ea26c6a42cfbade47182c7ce474", 13:58:34 INFO - "slavebuilddir": "test" 13:58:34 INFO - } 13:58:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 13:58:34 INFO - Running post-action listener: _resource_record_post_action 13:58:34 INFO - [mozharness: 2016-10-09 20:58:34.815187Z] Finished read-buildbot-config step (success) 13:58:34 INFO - [mozharness: 2016-10-09 20:58:34.815301Z] Running download-and-extract step. 13:58:34 INFO - Running pre-action listener: _resource_record_pre_action 13:58:34 INFO - Running main action method: download_and_extract 13:58:34 INFO - mkdir: /builds/slave/test/build/tests 13:58:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:58:34 INFO - trying https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 13:58:34 INFO - Downloading https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/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 13:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/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 13:58:36 INFO - Downloaded 1199 bytes. 13:58:36 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 13:58:36 INFO - Using the following test package requirements: 13:58:36 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 13:58:36 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:58:36 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 13:58:36 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:58:36 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 13:58:36 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:58:36 INFO - u'jsshell-mac.zip'], 13:58:36 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:58:36 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 13:58:36 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 13:58:36 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:58:36 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 13:58:36 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:58:36 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 13:58:36 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:58:36 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 13:58:36 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:58:36 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 13:58:36 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:58:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 13:58:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:58:36 INFO - Fetch https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 13:58:36 INFO - Http code: 200 13:58:36 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 13:58:36 INFO - x-amz-cf-id: 92PiBUiCp9_7Wurt6pTEUQLpnyx41HoYu2iD_6azzR0x1zDxUhnXkA== 13:58:36 INFO - x-amz-version-id: 7jQRAxARNeCyFnuRZa.AaS5ZedIbhV9N 13:58:37 INFO - Expected file size: 16496335 13:58:37 INFO - Obtained file size: 16496335 13:58:43 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 13:58:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 13:58:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:58:43 INFO - Fetch https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 13:58:44 INFO - Http code: 200 13:58:44 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 13:58:44 INFO - x-amz-cf-id: mgpqGwA405jO9opBcV9A65JOAr8c2UmlgtbeT9z29q1mYCKEraFLFQ== 13:58:44 INFO - x-amz-version-id: 7jQRAxARNeCyFnuRZa.AaS5ZedIbhV9N 13:58:44 INFO - Expected file size: 16496335 13:58:44 INFO - Obtained file size: 16496335 13:58:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 13:58:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:58:50 INFO - Fetch https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 13:58:51 INFO - Http code: 200 13:58:51 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 13:58:51 INFO - x-amz-cf-id: yDYvLG16ShZQCWMLNoXQK6A_unywglJ3mFe-WdmdYMcKP4KcCGuCqw== 13:58:51 INFO - x-amz-version-id: b8w1LvpEZpNWaGfXeVR_hLlW2i4vVGMg 13:58:51 INFO - Expected file size: 14409006 13:58:51 INFO - Obtained file size: 14409006 13:58:52 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')]} 13:58:52 INFO - trying https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:58:52 INFO - Downloading https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 13:58:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/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 13:58:55 INFO - Downloaded 111710727 bytes. 13:58:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:58:55 INFO - mkdir: /builds/slave/test/properties 13:58:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:58:55 INFO - Writing to file /builds/slave/test/properties/build_url 13:58:55 INFO - Contents: 13:58:55 INFO - build_url:https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:58:56 INFO - Running post-action listener: _resource_record_post_action 13:58:56 INFO - Running post-action listener: set_extra_try_arguments 13:58:56 INFO - [mozharness: 2016-10-09 20:58:56.226018Z] Finished download-and-extract step (success) 13:58:56 INFO - [mozharness: 2016-10-09 20:58:56.226154Z] Running populate-webroot step. 13:58:56 INFO - Running pre-action listener: _resource_record_pre_action 13:58:56 INFO - Running main action method: populate_webroot 13:58:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:58:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:58:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:58:56 INFO - '--tppagecycles', 13:58:56 INFO - '7'], 13:58:56 INFO - 'tests': ['tsvgm']}}, 13:58:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:58:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:58:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:58:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:58:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:58:56 INFO - 'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:58:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:58:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:58:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:58:56 INFO - 'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['damp', 'tps']}, 13:58:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:58:56 INFO - 'tests': ['damp', 'tps']}, 13:58:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['dromaeo_dom']}, 13:58:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:58:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['basic_compositor_video']}, 13:58:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:58:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['a11yr', 13:58:56 INFO - 'ts_paint', 13:58:56 INFO - 'tpaint', 13:58:56 INFO - 'sessionrestore', 13:58:56 INFO - 'sessionrestore_no_auto_restore', 13:58:56 INFO - 'tabpaint']}, 13:58:56 INFO - 'other-e10s': {'tests': ['a11yr', 13:58:56 INFO - 'ts_paint', 13:58:56 INFO - 'tpaint', 13:58:56 INFO - 'sessionrestore', 13:58:56 INFO - 'sessionrestore_no_auto_restore', 13:58:56 INFO - 'tabpaint']}, 13:58:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:58:56 INFO - 'ts_paint', 13:58:56 INFO - 'tpaint', 13:58:56 INFO - 'sessionrestore', 13:58:56 INFO - 'sessionrestore_no_auto_restore', 13:58:56 INFO - 'tabpaint']}, 13:58:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:58:56 INFO - 'ts_paint', 13:58:56 INFO - 'tpaint', 13:58:56 INFO - 'sessionrestore', 13:58:56 INFO - 'sessionrestore_no_auto_restore', 13:58:56 INFO - 'tabpaint']}, 13:58:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['a11yr', 13:58:56 INFO - 'ts_paint', 13:58:56 INFO - 'tpaint', 13:58:56 INFO - 'sessionrestore', 13:58:56 INFO - 'sessionrestore_no_auto_restore', 13:58:56 INFO - 'tabpaint']}, 13:58:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['a11yr', 13:58:56 INFO - 'ts_paint', 13:58:56 INFO - 'tpaint', 13:58:56 INFO - 'sessionrestore', 13:58:56 INFO - 'sessionrestore_no_auto_restore', 13:58:56 INFO - 'tabpaint']}, 13:58:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['tsvgx', 13:58:56 INFO - 'tsvgr_opacity', 13:58:56 INFO - 'tart', 13:58:56 INFO - 'tscrollx', 13:58:56 INFO - 'cart']}, 13:58:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:58:56 INFO - 'tsvgr_opacity', 13:58:56 INFO - 'tart', 13:58:56 INFO - 'tscrollx', 13:58:56 INFO - 'cart']}, 13:58:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:58:56 INFO - 'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['tp5o']}, 13:58:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:58:56 INFO - 'tests': ['tp5o']}, 13:58:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:58:56 INFO - 'talos_options': ['--disable-e10s', 13:58:56 INFO - '--xperf_path', 13:58:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:58:56 INFO - 'tests': ['tp5n']}, 13:58:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:58:56 INFO - 'talos_options': ['--xperf_path', 13:58:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:58:56 INFO - 'tests': ['tp5n']}}, 13:58:56 INFO - 'talos.zip': {'path': '', 13:58:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:58:56 INFO - Running post-action listener: _resource_record_post_action 13:58:56 INFO - [mozharness: 2016-10-09 20:58:56.244821Z] Finished populate-webroot step (success) 13:58:56 INFO - [mozharness: 2016-10-09 20:58:56.244928Z] Running create-virtualenv step. 13:58:56 INFO - Running pre-action listener: _resource_record_pre_action 13:58:56 INFO - Running main action method: create_virtualenv 13:58:56 INFO - Creating virtualenv /builds/slave/test/build/venv 13:58:56 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 13:58:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:58:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:58:56 INFO - Using real prefix '/tools/python27' 13:58:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:58:57 INFO - Installing distribute.............................................................................................................................................................................................done. 13:58:59 INFO - Installing pip.................done. 13:58:59 INFO - Return code: 0 13:58:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:58:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:59:00 INFO - Reading from file tmpfile_stdout 13:59:00 INFO - Output received: 13:59:00 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) 13:59:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:00 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 0x108f94490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbfe0d5ff20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108da8d30>, '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 0x108bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108da0b20>, '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.9joIxGepQg/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.GRsJe4EG39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 13:59:00 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 13:59:00 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 13:59:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9joIxGepQg/Render', 13:59:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:00 INFO - 'HOME': '/Users/cltbld', 13:59:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:00 INFO - 'LOGNAME': 'cltbld', 13:59:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:00 INFO - 'MOZ_NO_REMOTE': '1', 13:59:00 INFO - 'NO_EM_RESTART': '1', 13:59:00 INFO - 'PAGER': '/bin/cat', 13:59:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:00 INFO - 'PWD': '/builds/slave/test', 13:59:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:00 INFO - 'SHELL': '/bin/bash', 13:59:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GRsJe4EG39/Listeners', 13:59:00 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 13:59:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:00 INFO - 'USER': 'cltbld', 13:59:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:00 INFO - 'XPC_FLAGS': '0x0', 13:59:00 INFO - 'XPC_SERVICE_NAME': '0', 13:59:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:00 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 13:59:00 INFO - Cleaning up... 13:59:00 INFO - Return code: 0 13:59:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:00 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 0x108f94490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbfe0d5ff20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108da8d30>, '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 0x108bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108da0b20>, '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.9joIxGepQg/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.GRsJe4EG39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 13:59:00 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 13:59:00 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 13:59:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9joIxGepQg/Render', 13:59:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:00 INFO - 'HOME': '/Users/cltbld', 13:59:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:00 INFO - 'LOGNAME': 'cltbld', 13:59:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:00 INFO - 'MOZ_NO_REMOTE': '1', 13:59:00 INFO - 'NO_EM_RESTART': '1', 13:59:00 INFO - 'PAGER': '/bin/cat', 13:59:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:00 INFO - 'PWD': '/builds/slave/test', 13:59:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:00 INFO - 'SHELL': '/bin/bash', 13:59:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GRsJe4EG39/Listeners', 13:59:00 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 13:59:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:00 INFO - 'USER': 'cltbld', 13:59:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:00 INFO - 'XPC_FLAGS': '0x0', 13:59:00 INFO - 'XPC_SERVICE_NAME': '0', 13:59:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:00 INFO - Downloading/unpacking psutil>=3.1.1 13:59:00 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 13:59:00 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 13:59:00 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 13:59:00 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 13:59:00 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 13:59:00 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 13:59:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:59:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:59:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:59:04 INFO - Installing collected packages: psutil 13:59:04 INFO - Running setup.py install for psutil 13:59:04 INFO - building 'psutil._psutil_osx' extension 13:59:04 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 13:59:05 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 13:59:05 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 13:59:05 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 13:59:05 INFO - building 'psutil._psutil_posix' extension 13:59:05 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 13:59:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:59:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:59:05 INFO - ^ 13:59:05 INFO - 1 warning generated. 13:59:05 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 13:59:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:59:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:59:05 INFO - Successfully installed psutil 13:59:05 INFO - Cleaning up... 13:59:05 INFO - Return code: 0 13:59:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:59:05 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')]} 13:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:05 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')]} 13:59:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:05 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 0x108f94490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbfe0d5ff20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108da8d30>, '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 0x108bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108da0b20>, '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.9joIxGepQg/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.GRsJe4EG39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 13:59:05 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 13:59:05 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 13:59:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9joIxGepQg/Render', 13:59:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:05 INFO - 'HOME': '/Users/cltbld', 13:59:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:05 INFO - 'LOGNAME': 'cltbld', 13:59:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:05 INFO - 'MOZ_NO_REMOTE': '1', 13:59:05 INFO - 'NO_EM_RESTART': '1', 13:59:05 INFO - 'PAGER': '/bin/cat', 13:59:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:05 INFO - 'PWD': '/builds/slave/test', 13:59:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:05 INFO - 'SHELL': '/bin/bash', 13:59:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GRsJe4EG39/Listeners', 13:59:05 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 13:59:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:05 INFO - 'USER': 'cltbld', 13:59:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:05 INFO - 'XPC_FLAGS': '0x0', 13:59:05 INFO - 'XPC_SERVICE_NAME': '0', 13:59:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:59:06 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 13:59:06 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 13:59:06 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 13:59:06 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 13:59:06 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 13:59:06 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 13:59:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:59:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:59:08 INFO - Installing collected packages: mozsystemmonitor 13:59:08 INFO - Running setup.py install for mozsystemmonitor 13:59:08 INFO - Successfully installed mozsystemmonitor 13:59:08 INFO - Cleaning up... 13:59:08 INFO - Return code: 0 13:59:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108f94490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbfe0d5ff20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108da8d30>, '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 0x108bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108da0b20>, '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.9joIxGepQg/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.GRsJe4EG39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 13:59:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:59:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:59:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9joIxGepQg/Render', 13:59:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:08 INFO - 'HOME': '/Users/cltbld', 13:59:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:08 INFO - 'LOGNAME': 'cltbld', 13:59:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:08 INFO - 'MOZ_NO_REMOTE': '1', 13:59:08 INFO - 'NO_EM_RESTART': '1', 13:59:08 INFO - 'PAGER': '/bin/cat', 13:59:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:08 INFO - 'PWD': '/builds/slave/test', 13:59:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:08 INFO - 'SHELL': '/bin/bash', 13:59:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GRsJe4EG39/Listeners', 13:59:08 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 13:59:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:08 INFO - 'USER': 'cltbld', 13:59:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:08 INFO - 'XPC_FLAGS': '0x0', 13:59:08 INFO - 'XPC_SERVICE_NAME': '0', 13:59:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:09 INFO - Downloading/unpacking jsonschema==2.5.1 13:59:09 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 13:59:09 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 13:59:09 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 13:59:09 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 13:59:09 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 13:59:09 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 13:59:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:59:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:59:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:12 INFO - Downloading functools32-3.2.3-2.tar.gz 13:59:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:59:12 INFO - warning: no files found matching '*.txt' 13:59:12 INFO - no previously-included directories found matching 'build' 13:59:12 INFO - no previously-included directories found matching 'dist' 13:59:12 INFO - no previously-included directories found matching '.git*' 13:59:12 INFO - Installing collected packages: jsonschema, functools32 13:59:12 INFO - Running setup.py install for functools32 13:59:12 INFO - warning: no files found matching '*.txt' 13:59:12 INFO - no previously-included directories found matching 'build' 13:59:12 INFO - no previously-included directories found matching 'dist' 13:59:12 INFO - no previously-included directories found matching '.git*' 13:59:12 INFO - Successfully installed jsonschema functools32 13:59:12 INFO - Cleaning up... 13:59:12 INFO - Return code: 0 13:59:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:59:12 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')]} 13:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:12 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')]} 13:59:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:12 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 0x108f94490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbfe0d5ff20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108da8d30>, '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 0x108bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108da0b20>, '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.9joIxGepQg/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.GRsJe4EG39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 13:59:12 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 13:59:12 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 13:59:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9joIxGepQg/Render', 13:59:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:12 INFO - 'HOME': '/Users/cltbld', 13:59:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:12 INFO - 'LOGNAME': 'cltbld', 13:59:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:12 INFO - 'MOZ_NO_REMOTE': '1', 13:59:12 INFO - 'NO_EM_RESTART': '1', 13:59:12 INFO - 'PAGER': '/bin/cat', 13:59:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:12 INFO - 'PWD': '/builds/slave/test', 13:59:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:12 INFO - 'SHELL': '/bin/bash', 13:59:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GRsJe4EG39/Listeners', 13:59:12 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 13:59:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:12 INFO - 'USER': 'cltbld', 13:59:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:12 INFO - 'XPC_FLAGS': '0x0', 13:59:12 INFO - 'XPC_SERVICE_NAME': '0', 13:59:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:59:12 INFO - Cleaning up... 13:59:12 INFO - Return code: 0 13:59:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:59:12 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')]} 13:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:12 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')]} 13:59:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:12 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 0x108f94490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbfe0d5ff20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108da8d30>, '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 0x108bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108da0b20>, '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.9joIxGepQg/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.GRsJe4EG39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 13:59:12 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 13:59:12 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 13:59:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9joIxGepQg/Render', 13:59:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:12 INFO - 'HOME': '/Users/cltbld', 13:59:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:12 INFO - 'LOGNAME': 'cltbld', 13:59:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:12 INFO - 'MOZ_NO_REMOTE': '1', 13:59:12 INFO - 'NO_EM_RESTART': '1', 13:59:12 INFO - 'PAGER': '/bin/cat', 13:59:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:12 INFO - 'PWD': '/builds/slave/test', 13:59:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:12 INFO - 'SHELL': '/bin/bash', 13:59:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GRsJe4EG39/Listeners', 13:59:12 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 13:59:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:12 INFO - 'USER': 'cltbld', 13:59:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:12 INFO - 'XPC_FLAGS': '0x0', 13:59:12 INFO - 'XPC_SERVICE_NAME': '0', 13:59:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:12 INFO - Downloading/unpacking blobuploader==1.2.4 13:59:12 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 13:59:12 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 13:59:12 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 13:59:12 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 13:59:12 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 13:59:12 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 13:59:15 INFO - Downloading blobuploader-1.2.4.tar.gz 13:59:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:59:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:59:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:59:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:59:16 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 13:59:16 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 13:59:16 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 13:59:16 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 13:59:16 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 13:59:16 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 13:59:16 INFO - Downloading docopt-0.6.1.tar.gz 13:59:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:59:16 INFO - Installing collected packages: blobuploader, requests, docopt 13:59:16 INFO - Running setup.py install for blobuploader 13:59:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:59:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:59:16 INFO - Running setup.py install for requests 13:59:17 INFO - Running setup.py install for docopt 13:59:17 INFO - Successfully installed blobuploader requests docopt 13:59:17 INFO - Cleaning up... 13:59:17 INFO - Return code: 0 13:59:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:17 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 0x108f94490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbfe0d5ff20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108da8d30>, '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 0x108bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108da0b20>, '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.9joIxGepQg/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.GRsJe4EG39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 13:59:17 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 13:59:17 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 13:59:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9joIxGepQg/Render', 13:59:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:17 INFO - 'HOME': '/Users/cltbld', 13:59:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:17 INFO - 'LOGNAME': 'cltbld', 13:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:17 INFO - 'MOZ_NO_REMOTE': '1', 13:59:17 INFO - 'NO_EM_RESTART': '1', 13:59:17 INFO - 'PAGER': '/bin/cat', 13:59:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:17 INFO - 'PWD': '/builds/slave/test', 13:59:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:17 INFO - 'SHELL': '/bin/bash', 13:59:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GRsJe4EG39/Listeners', 13:59:17 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 13:59:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:17 INFO - 'USER': 'cltbld', 13:59:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:17 INFO - 'XPC_FLAGS': '0x0', 13:59:17 INFO - 'XPC_SERVICE_NAME': '0', 13:59:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:59:17 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-F4thUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:59:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:59:17 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-uLbRzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:59:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-M8MpDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:59:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-2I3Vnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:59:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-MTe6IS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:59:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-yhV0S9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:59:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-THV0Mm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:59:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-rqKbs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:59:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-CN4z8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:59:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-6Vcq8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:59:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-ZqyHBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:59:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-7ElW7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:59:19 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-vCXVyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:59:19 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-x9hpKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:19 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-MMVVZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:59:19 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-CRPBs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:59:19 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-AHsWvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:59:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:59:19 INFO - Running setup.py install for manifestparser 13:59:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:59:19 INFO - Running setup.py install for mozcrash 13:59:19 INFO - Running setup.py install for mozdebug 13:59:19 INFO - Running setup.py install for mozdevice 13:59:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:59:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:59:20 INFO - Running setup.py install for mozfile 13:59:20 INFO - Running setup.py install for mozhttpd 13:59:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:59:20 INFO - Running setup.py install for mozinfo 13:59:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:59:20 INFO - Running setup.py install for mozInstall 13:59:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:59:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:59:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:59:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:59:20 INFO - Running setup.py install for mozleak 13:59:20 INFO - Running setup.py install for mozlog 13:59:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:59:21 INFO - Running setup.py install for moznetwork 13:59:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:59:21 INFO - Running setup.py install for mozprocess 13:59:21 INFO - Running setup.py install for mozprofile 13:59:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:59:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:59:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:59:21 INFO - Running setup.py install for mozrunner 13:59:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:59:21 INFO - Running setup.py install for mozscreenshot 13:59:21 INFO - Running setup.py install for moztest 13:59:21 INFO - Running setup.py install for mozversion 13:59:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:59:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:59:22 INFO - Cleaning up... 13:59:22 INFO - Return code: 0 13:59:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:22 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 0x108f94490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbfe0d5ff20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108da8d30>, '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 0x108bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108da0b20>, '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.9joIxGepQg/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.GRsJe4EG39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 13:59:22 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 13:59:22 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 13:59:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9joIxGepQg/Render', 13:59:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:22 INFO - 'HOME': '/Users/cltbld', 13:59:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:22 INFO - 'LOGNAME': 'cltbld', 13:59:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:22 INFO - 'MOZ_NO_REMOTE': '1', 13:59:22 INFO - 'NO_EM_RESTART': '1', 13:59:22 INFO - 'PAGER': '/bin/cat', 13:59:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:22 INFO - 'PWD': '/builds/slave/test', 13:59:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:22 INFO - 'SHELL': '/bin/bash', 13:59:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GRsJe4EG39/Listeners', 13:59:22 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 13:59:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:22 INFO - 'USER': 'cltbld', 13:59:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:22 INFO - 'XPC_FLAGS': '0x0', 13:59:22 INFO - 'XPC_SERVICE_NAME': '0', 13:59:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:59:22 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-CpjRss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:59:22 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)) 13:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:59:22 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Eo4e2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:59:22 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)) 13:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:59:22 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-fLc70f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:59:22 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)) 13:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:59:22 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-kiWjK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:59:22 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)) 13:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:59:22 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-SvqobZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:59:22 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)) 13:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:59:22 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-a15yg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:59:23 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)) 13:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:59:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-oxeR3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:59:23 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)) 13:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:59:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-XDil0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:59:23 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)) 13:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:59:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-VxZKze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:59:23 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)) 13:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:59:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-uu38cC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:59:23 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)) 13:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:59:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-R9pfYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:59:23 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)) 13:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:59:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-kCI14I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:59:23 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)) 13:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:59:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-qD6qgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:59:23 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)) 13:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:59:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-e50nXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:59:23 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)) 13:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-QW5dtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:23 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)) 13:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:59:24 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-9nODdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:59:24 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)) 13:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:59:24 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-oRbV7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:59:24 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)) 13:59:24 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)) 13:59:24 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)) 13:59:24 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)) 13:59:24 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)) 13:59:24 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)) 13:59:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:59:24 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 13:59:24 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 13:59:24 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 13:59:24 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 13:59:24 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 13:59:24 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 13:59:26 INFO - Downloading blessings-1.6.tar.gz 13:59:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:59:26 INFO - Installing collected packages: blessings 13:59:26 INFO - Running setup.py install for blessings 13:59:26 INFO - Successfully installed blessings 13:59:26 INFO - Cleaning up... 13:59:27 INFO - Return code: 0 13:59:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:59:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:59:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:59:27 INFO - Reading from file tmpfile_stdout 13:59:27 INFO - Current package versions: 13:59:27 INFO - blessings == 1.6 13:59:27 INFO - blobuploader == 1.2.4 13:59:27 INFO - docopt == 0.6.1 13:59:27 INFO - functools32 == 3.2.3-2 13:59:27 INFO - jsonschema == 2.5.1 13:59:27 INFO - manifestparser == 1.1 13:59:27 INFO - mozInstall == 1.12 13:59:27 INFO - mozcrash == 0.17 13:59:27 INFO - mozdebug == 0.1 13:59:27 INFO - mozdevice == 0.48 13:59:27 INFO - mozfile == 1.2 13:59:27 INFO - mozhttpd == 0.7 13:59:27 INFO - mozinfo == 0.9 13:59:27 INFO - mozleak == 0.1 13:59:27 INFO - mozlog == 3.4 13:59:27 INFO - moznetwork == 0.27 13:59:27 INFO - mozprocess == 0.23 13:59:27 INFO - mozprofile == 0.28 13:59:27 INFO - mozrunner == 6.12 13:59:27 INFO - mozscreenshot == 0.1 13:59:27 INFO - mozsystemmonitor == 0.3 13:59:27 INFO - moztest == 0.7 13:59:27 INFO - mozversion == 1.4 13:59:27 INFO - psutil == 3.1.1 13:59:27 INFO - requests == 1.2.3 13:59:27 INFO - wsgiref == 0.1.2 13:59:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:59:27 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')]} 13:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:27 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')]} 13:59:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:27 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 0x108f94490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbfe0d5ff20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108da8d30>, '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 0x108bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108da0b20>, '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.9joIxGepQg/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.GRsJe4EG39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 13:59:27 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 13:59:27 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 13:59:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9joIxGepQg/Render', 13:59:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:27 INFO - 'HOME': '/Users/cltbld', 13:59:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:27 INFO - 'LOGNAME': 'cltbld', 13:59:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:27 INFO - 'MOZ_NO_REMOTE': '1', 13:59:27 INFO - 'NO_EM_RESTART': '1', 13:59:27 INFO - 'PAGER': '/bin/cat', 13:59:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:27 INFO - 'PWD': '/builds/slave/test', 13:59:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:27 INFO - 'SHELL': '/bin/bash', 13:59:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GRsJe4EG39/Listeners', 13:59:27 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 13:59:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:27 INFO - 'USER': 'cltbld', 13:59:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:27 INFO - 'XPC_FLAGS': '0x0', 13:59:27 INFO - 'XPC_SERVICE_NAME': '0', 13:59:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:27 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)) 13:59:27 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)) 13:59:27 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)) 13:59:27 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)) 13:59:27 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)) 13:59:27 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)) 13:59:27 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)) 13:59:27 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)) 13:59:27 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)) 13:59:27 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)) 13:59:27 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)) 13:59:27 INFO - Cleaning up... 13:59:27 INFO - Return code: 0 13:59:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:59:27 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')]} 13:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:27 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')]} 13:59:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:27 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 0x108f94490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbfe0d5ff20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108da8d30>, '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 0x108bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108da0b20>, '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.9joIxGepQg/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.GRsJe4EG39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 13:59:27 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 13:59:27 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 13:59:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9joIxGepQg/Render', 13:59:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:27 INFO - 'HOME': '/Users/cltbld', 13:59:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:27 INFO - 'LOGNAME': 'cltbld', 13:59:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:27 INFO - 'MOZ_NO_REMOTE': '1', 13:59:27 INFO - 'NO_EM_RESTART': '1', 13:59:27 INFO - 'PAGER': '/bin/cat', 13:59:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:27 INFO - 'PWD': '/builds/slave/test', 13:59:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:27 INFO - 'SHELL': '/bin/bash', 13:59:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GRsJe4EG39/Listeners', 13:59:27 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 13:59:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:27 INFO - 'USER': 'cltbld', 13:59:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:27 INFO - 'XPC_FLAGS': '0x0', 13:59:27 INFO - 'XPC_SERVICE_NAME': '0', 13:59:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:59:27 INFO - Cleaning up... 13:59:27 INFO - Return code: 0 13:59:27 INFO - Running post-action listener: _resource_record_post_action 13:59:27 INFO - Running post-action listener: _start_resource_monitoring 13:59:27 INFO - Starting resource monitoring. 13:59:27 INFO - [mozharness: 2016-10-09 20:59:27.896357Z] Finished create-virtualenv step (success) 13:59:27 INFO - [mozharness: 2016-10-09 20:59:27.897390Z] Running install step. 13:59:27 INFO - Running pre-action listener: _resource_record_pre_action 13:59:27 INFO - Running main action method: install 13:59:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:59:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:59:28 INFO - Reading from file tmpfile_stdout 13:59:28 INFO - Detecting whether we're running mozinstall >=1.0... 13:59:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:59:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:59:28 INFO - Reading from file tmpfile_stdout 13:59:28 INFO - Output received: 13:59:28 INFO - Usage: mozinstall [options] installer 13:59:28 INFO - Options: 13:59:28 INFO - -h, --help show this help message and exit 13:59:28 INFO - -d DEST, --destination=DEST 13:59:28 INFO - Directory to install application into. [default: 13:59:28 INFO - "/builds/slave/test"] 13:59:28 INFO - --app=APP Application being installed. [default: firefox] 13:59:28 INFO - mkdir: /builds/slave/test/build/application 13:59:28 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'] 13:59:28 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 14:00:02 INFO - Reading from file tmpfile_stdout 14:00:02 INFO - Output received: 14:00:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:00:02 INFO - Running post-action listener: _resource_record_post_action 14:00:02 INFO - [mozharness: 2016-10-09 21:00:02.387916Z] Finished install step (success) 14:00:02 INFO - [mozharness: 2016-10-09 21:00:02.388047Z] Running run-tests step. 14:00:02 INFO - Running pre-action listener: _resource_record_pre_action 14:00:02 INFO - Running main action method: run_tests 14:00:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:00:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:00:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:00:02 INFO - 2016-10-09 14:00:02.401 screenresolution[1653:10961] starting screenresolution argv=screenresolution get 14:00:02 INFO - 2016-10-09 14:00:02.404 screenresolution[1653:10961] Display 0: 1600x1200x32@60 14:00:02 INFO - 2016-10-09 14:00:02.409 screenresolution[1654:10963] starting screenresolution argv=screenresolution list 14:00:02 INFO - Available Modes on Display 0 14:00:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:00:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:00:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:00:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:00:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:00:02 INFO - Intel Iris: 14:00:02 INFO - Chipset Model: Intel Iris 14:00:02 INFO - Type: GPU 14:00:02 INFO - Bus: Built-In 14:00:02 INFO - VRAM (Dynamic, Max): 1536 MB 14:00:02 INFO - Vendor: Intel (0x8086) 14:00:02 INFO - Device ID: 0x0a2e 14:00:02 INFO - Revision ID: 0x0009 14:00:02 INFO - Displays: 14:00:02 INFO - Display: 14:00:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:00:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:00:02 INFO - Display Serial Number: 1600x1200 60 14:00:02 INFO - Main Display: Yes 14:00:02 INFO - Mirror: Off 14:00:02 INFO - Online: Yes 14:00:02 INFO - Rotation: Supported 14:00:02 INFO - Return code: 0 14:00:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:00:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:00:02 INFO - Python 2.7.3 14:00:02 INFO - Return code: 0 14:00:02 INFO - grabbing minidump binary from tooltool 14:00: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')]} 14:00:02 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 0x108da8d30>, '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 0x108bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108da0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:00:02 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 14:00:02 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 14:00:02 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 14:00:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:00:02 INFO - Return code: 0 14:00:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:00:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:00:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:00:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:00:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:00:02 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/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--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 14:00:02 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/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0324 --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 14:00:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9joIxGepQg/Render', 14:00:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:02 INFO - 'HOME': '/Users/cltbld', 14:00:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:02 INFO - 'LOGNAME': 'cltbld', 14:00:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:00:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:02 INFO - 'MOZ_NO_REMOTE': '1', 14:00:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:00:02 INFO - 'NO_EM_RESTART': '1', 14:00:02 INFO - 'PAGER': '/bin/cat', 14:00:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:02 INFO - 'PWD': '/builds/slave/test', 14:00:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:00:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:02 INFO - 'SHELL': '/bin/bash', 14:00:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GRsJe4EG39/Listeners', 14:00:02 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 14:00:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:02 INFO - 'USER': 'cltbld', 14:00:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:02 INFO - 'XPC_FLAGS': '0x0', 14:00:02 INFO - 'XPC_SERVICE_NAME': '0', 14:00:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:02 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/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--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 14:00:03 INFO - mozversion application_buildid: 20161009120418 14:00:03 INFO - mozversion application_changeset: 0ba0016683aef98cd7a64ff5e31f23e8d7eb08aa 14:00:03 INFO - mozversion application_display_name: Nightly 14:00:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:00:03 INFO - mozversion application_name: Firefox 14:00:03 INFO - mozversion application_remotingname: firefox 14:00:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:00:03 INFO - mozversion application_vendor: Mozilla 14:00:03 INFO - mozversion application_version: 52.0a1 14:00:03 INFO - mozversion platform_buildid: 20161009120418 14:00:03 INFO - mozversion platform_changeset: 0ba0016683aef98cd7a64ff5e31f23e8d7eb08aa 14:00:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:00:03 INFO - mozversion platform_version: 52.0a1 14:00:03 INFO - using testdate: 1476046803 14:00:03 INFO - actual date: 1476046803 14:00:03 INFO - starting webserver on 'localhost:49271' 14:00:03 INFO - SUITE-START | Running 2 tests 14:00:03 INFO - TEST-START | dromaeo_css 14:00:03 INFO - operating with platform_type : mac_ 14:00:03 INFO - Initialising browser for dromaeo_css test... 14:00:03 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpvAV1_C/profile http://localhost:49271/getInfo.html) 14:00:05 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 14:00:05 INFO - PROCESS | 1664 | colorDepth:24 14:00:05 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 14:00:05 INFO - PROCESS | 1664 | __metrics 14:00:05 INFO - PROCESS | 1664 | 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" 14:00:05 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:00:06 INFO - TEST-INFO | 1664: exit 0 14:00:06 INFO - Browser initialized. 14:00:06 INFO - Running cycle 1/1 for dromaeo_css test... 14:00:06 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpvAV1_C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:00:18 INFO - PROCESS | 1667 | RSS: Main: 234459136 14:00:18 INFO - PROCESS | 1667 | 14:01:09 INFO - PROCESS | 1667 | 1476046869609 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] 14:03:22 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 14:03:23 INFO - PROCESS | 1667 | RSS: Main: 342880256 14:03:23 INFO - PROCESS | 1667 | 14:06:26 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 14:06:27 INFO - PROCESS | 1667 | RSS: Main: 371920896 14:06:27 INFO - PROCESS | 1667 | 14:09:29 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 14:09:30 INFO - PROCESS | 1667 | RSS: Main: 332079104 14:09:30 INFO - PROCESS | 1667 | 14:12:32 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 14:12:33 INFO - PROCESS | 1667 | RSS: Main: 344211456 14:12:33 INFO - PROCESS | 1667 | 14:15:36 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 14:15:37 INFO - PROCESS | 1667 | RSS: Main: 327852032 14:15:37 INFO - PROCESS | 1667 | 14:18:40 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 14:18:40 INFO - PROCESS | 1667 | RSS: Main: 388190208 14:18:40 INFO - PROCESS | 1667 | 14:18:40 INFO - PROCESS | 1667 | __start_tp_report 14:18:40 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 14:18:40 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 14:18:40 INFO - PROCESS | 1667 | |i|pagename|runs| 14:18:40 INFO - PROCESS | 1667 | |0;dojo.html;3349.2966477039827;3354.7819391739536;3359.630440651528;3408.556887604611;3420.880269190579;5611.270534830466;5616.41027692092;5692.971535142318;5725.427457254273;5761.049426844571;5659.773609055633;5670.149477578365;5719.513841323493;5724.055530837417;5731.541476681866;4492.640588752892;5088.77100530477;5203.245529398235;5214.139666954967;5281.63028587999;5213.973930130363;5219.217117432378;5220.138677118271;5236.057509648262;5247.081760691864;5089.491050894918;5301.231321458406;5356.892862142755;5360.490753378443;5371.462853714617;4360.302351623725;5156.1750119981;5220.530152286313;5262.789488420459;5291.947080529184;5119.769610367543;5159.380874295071;5175.844724658266;5218;5231.921521177185;5563.248961390201;5573.526250268732;5629.127485239795;5766.192733017381;5791.681457519835;6195.1946246988055;6197.442230199304;6199.535034872402;6206.565540412173;6240.31356550779;6114.908276375858;6134.754609815602;6170.938290617081;6211.16149319845;6224.688765561704;5772.163036359704;5778.162166485878;5820.447057529528;5824.767009319579;5853.88292234153;6271.560990730696;6304.401081897213;6310.274318453345;6314.873702525923;6321.620702757849;6158.137860699506;6161.260648722182;6162.476189523851;6168.907466387918;6171.814845554641;6115.909724157157;6121.724522396503;6133.1413602095745;6158.322584515699;6159.23009623797;6174.9691251544355;6196.47329976948;6200.720967556471;6203;6206.751729930843;6152.352457473413;6167.414095660905;6204.689765511796;6206.255249370104;6228.03465462854;4834.468208497063;4891.608671306287;4900.926486102711;4946.851594452172;4985.426675932242;4397.714148580332;4419.911601768011;4438.578335058164;4472.821087156477;4508.842190523316;5814.069748840166;5824.504917082011;5832.620879642895;5832.737526811304;5874.941250587525;6115.541334399938;6183.876322473526;6189.164462797555;6194.907076393858;6215.160953271341;5642.971785141048;5711.8000869969355;5752.482276595127;5766.394528574523;5776.797812076557;5398.6220964532895;5420.214068960057;5423.972880135574;5437.401885792559;5455.454454554513;6316.557840951089;6326.683665816783;6326.841828954313;6328.778492752732;6348.9047664286;6111.755529778759;6156.569040167145;6213.565050446426;6228.408301211378;6260.248770147611;6236.532260080603;6300.149479820349;6315.400036996662;6321.810345689637;6344.238691357066;6149.907751383822;6166.321704612579;6176.845578860564;6179.938200618116;6188.938110618837;6135.478484328882;6163.9383606163365;6176.444120029039;6194.566380353332;6241.594296370721;6071.726772295169;6092.360302168208;6116.822755456359;6138.235132557229;6151.892659321186;6106.564957235135;6136.392033385704;6152.876942461216;6156.538259630456;6165.876682466236;5769.048107062287;5970.402959703995;6012.428819262163;6014.097885317237;6058.969705151446;5961;6039.969800150971;6101.877962440815;6102.816915492543;6154.938450615616;5802.535797136304;5978.641281523122;5989.071693887455;6017.368176341596;6034.064719968412 14:18:40 INFO - PROCESS | 1667 | |1;ext.html;469.14381254211065;474.7626186906547;493.565662217249;527.688663688424;528.2498851630684;33502.32995340095;39239.41140882889;39377.803110984474;39405.8029709851;40712.59287407125;40730.59269407305;41115;41159.971000724996;41246.38130428046;41358.37962430566;9646.903530964672;9816.50917454128;9849.21206303494;9860.605575776995;9861.359011664255;9657.758556036086;9751.707448776513;9762.853557196648;9763.609455621767;9772;9751.219902407825;9803.215742740564;9815.950920245388;9823.852642210373;9841.311108222428;9758.219342452623;9776.755581110458;9784.02159784023;9793.6082556698;9808.16630586401;8863.911360886374;9468.431894086378;9592.472414017226;9679.274054445874;9707.805843883083;39130.02174945621;39429.60570394317;39453.80273098645;39501.40747888784;39587;3262.559554460141;3271.1658527075683;3284.8521816518314;3464.7574669773126;3469.8959031229106;29627;29798.55302170469;30145.849270753726;30272.697273027206;31365.37269254602;3149.9370012599848;3216.8713251469912;3219.8390080495883;3242.951355729666;3276.082696844863;482462;482642.58678706735;483682.58158709336;483854;491591;54577.181342279895;54708;54826;54970;55006.17490737642;30852;32614.673853261163;33661.83169084188;57053.71473142608;57478.137827932616;15826.525204243891;15831.129287889047;16025.358985640676;16056.75914861278;16068.598285042968;12022.27866328023;12040.458179381949;12446.688832779253;12486.001119910567;12531.74936501283;16059.116748578706;18343.449696509128;18407.171677274553;18414.815851841577;18433.170507326933;6633.668316584151;6832.0776695146515;7431.99668044807;7600.695972161162;7832.765017049498;819.8852160697508;961.7932144588947;983.3755565216139;1018.277023313441;1046.6546039806844;26065.73934260671;27130.779114939876;27569.724302756716;29227.638627046268;29920.850395747882;803.5178892664354;996.835522138839;1014.4217795856291;1058.433737950193;1076.9730756731144;13900.009959167333;14541.563753087425;15038.473653422077;15079.849201508065;15309.540713778604;615531;624493;625356;628746;630695;54204.18693719598;54704.17943730847;54723;54726;54775.452245477034;1210.806270996654;1258.1507482449492;1259.785836407813;1350.581319790868;1366.5763613279914;27654.170374888785;30839.537406938463;32456.83771581127;32693.34613307768;33934.66065339414;55964.44035559592;58603.41396585979;58789;58925;59061.11408328964;497426;517222;595770;597422;598705;43329.35005974976;43375;43826.78086609547;44198.55801442073;44286.335704965095;38640.806795966964;46949;47420;47605.76197119131;49382.75308623434;32340.191495212806;40095.3985690209;40143;40312.79843600763;41507.37738933979;38763.41854872123;38793.418098729104;39630.009249768984;40747;40782;37668.43497347597;38109.61890381061;38581.807090965485;38790.6120938787;38972.41541376825;33859.32281354408;34598.30803383968;34810.30379392449;35395.82302088473;35726.46410303796;19015.42953711391;21282;25387.36531586725;25448;25745.871270643525 14:18:40 INFO - PROCESS | 1667 | |2;jquery.html;354.13590838354395;373.23859326972996;373.93269211541923;381.69464428457235;404.71063189819273;15144.848551514482;15356.308966096525;17216.91391543042;20906.16375344988;21376.465588360297;20076.69884951727;20328.28850990212;20332.69500957487;20564.588708225863;21633;5757.596968212227;5889.499392551616;5957.510622344414;6292.0876472911405;6301.873962520747;6058.969705151468;6170.290416602081;6236.532260080489;6252.687365631723;6258.06129080637;5840.737166827503;6079.179310793031;6114.510839132859;6174.536909731765;6200.751969921198;6127.35662755411;6197.225346831646;6203.937960620381;6207.25512938448;6209.950716263622;5242.239875218072;5556.388797232301;5595.804146854881;5607.915881261784;5619.129034999582;13397.464101435931;13428.731425371536;13433.529826456124;13628.318584070757;13688.452461901512;2132.936011919645;2238.865668059905;2635.472905418924;2810.3861663999774;2855.4431885782283;9179.311551633655;9490.667826626104;9532.094451027142;10288.537015834305;10581.73545661357;1836.7336736173313;1851.5556266496078;1853.5458812591;1870.093004892626;1873.325602782997;112909;123584;125377;125548;127020.36489817491;11119.166062545342;11308.491117899714;11489.885101148882;11516.424178791027;11756.35340056288;14540.127592344494;14568.49010284635;14582.781258281135;14604.269786510631;14611.561653150422;8187.631556579968;8506.702265420681;8547.35894807892;8635.050144484101;8657.177568131017;8367.414281000272;8394.496330220207;8438.45150065256;8558.957205213934;8561.957190214009;4430.401895744098;4520.389747384127;4528.162289976365;4601.401817763736;4610.608098311681;1647.7857878475722;1848.7798053284766;1890.3100368365722;1910.9140088695765;1919.4548388547075;827.5737994932615;899.9245901148196;912.9413173294179;967.7338731848657;1034.5086084109987;19547.609047818965;21855.562888742137;22072.2274720384;22121.004554795072;22253;770.4760762681318;850.6214734443237;883.2977782662798;915.4004127296631;944.197432182653;10947.233693641369;11346.319220846775;11411.48648310828;11483.942580287212;11501.597444089417;468174;551166;574956.5026098448;597202;602570;121351.78648213405;127029.36485317514;127348.6325683712;128151.35924320505;130022.69977300106;961.2646325560952;965.584798536636;987.5704068730125;988.9752756181153;989.9059589339001;18373.448796536126;18413.631727365646;18523.795953262994;18960.336388226347;18995.810041899404;47973.760131199124;48818.99999999858;49219.50780492149;54197;55530.72234638801;67948.32051679419;69800;70603;71354.64322678353;72031.27968720246;6861.867791814295;7088.397486213729;7212.098487689039;7222.89165662505;7989.121196668246;12628.431720572413;12848.678783030498;13386.26375549354;13438.46246150143;13569.79645305341;5148.542475226355;5225.503577160163;5267.841964741064;5299.152135658276;11071.187626496154;10244.846327304947;10732.463376831283;10866.511007004545;10961.945190273998;11371.601993930173;11144.275622084539;11454.82817757751;11680.824787628362;11685.065194784567;11818.054555635701;5538.972305138448;7130.821729456805;7139.571625702474;7344.596047217455;7413.925860741324;8850.955745221232;10969.725756856142;11122.499487523242;11238.550457981915;11579.073674105883 14:18:40 INFO - PROCESS | 1667 | |3;mootools.html;1381.8479967203612;1563.1871426858038;1573.3785154863829;1802.8828126171798;1942.5629233422474;7930.7620771376805;8399.11809260028;8672.653093876253;9015.963164236115;9032.729018129467;8854.468731876075;8948.776280592989;8953.328500362482;8959.059298773633;8968.775780605472;2083.3437467197823;2372.810175185981;2374.905003799846;2378.63131214662;2379.9048038078545;2314.456102815837;2355.693759811222;2359.2686267257204;2362.527494501098;2368.917087901924;2328.5924963131383;2350.9529809403803;2358.1039205102124;2363.2083252110565;2367.5146594947996;2114.9365519034454;2192.8487544039426;2306.606227242324;2332.3119679694532;2342.847714898526;2315.3169814904645;2321.6285394336824;2322.210448447519;2325.104834638659;2325.7557956414503;8778.692745753868;9002.279817614575;9002.729918102468;9005.099490051009;9045.864312035259;5231.110711179071;5455.481729235756;5467.289252397202;5474.753636086346;5477.863053423696;3033.620797400325;3674.1365779041903;3755.9248815023548;3765.7740535567955;3767.077066118791;1064.164630764846;1086.3916206924148;1760.287083731091;1830.9359172429033;1880.4358692392227;11890.989265912407;11942.343171125585;11974.52101915931;11974.64076077719;11982;4270.252705776476;4594.471635761864;4639.582437580567;4648.651351148677;4648.907021859543;9220.308476864262;9225.354225204172;9231.030741772152;9231.399959002778;9242.306826987868;5308.23030660552;5315.920261196085;5319.7340132993195;5321.627486075938;5329.040772660881;4157.334826427757;4455.1312494064;4636.119137363958;4983.90032199354;4994.076095922266;3183.3474137801695;3188.9681103188673;3516.542849429609;3717.628237176315;3814.732968692165;1138.408027825526;1145.1869172887177;1156.670348950545;1160.118310084325;1163.9825402618967;760.8858671199254;844.3329769482041;864.8227113441856;919.5310391700281;930.7859192385654;4482.4172857528965;5856.385079566584;6025.367336429699;6120.326764055951;6155.599886007485;829.7261903571807;900.1853322742928;957.1098878043347;962.5957098018849;1003.8996100390049;3919.3337132686943;4005.859794907164;4010.3783913493453;4031.9798401007806;4166.354215096665;10769.9461502692;10935.781284374269;11109.111271098292;11156.051735602403;11194.552217911356;11037.227394082498;11041.83437248442;11045;11063.668089957315;11066.39134847575;6547.574407663582;6982.720691172398;8241.711540096068;8314.168583141758;8344.958275208584;5099.413567439792;5670.773169073191;5985.551083668829;6002.429769171922;6205.2553693557065;8039.397045221749;10117.59529618807;10124;10230.539625716712;10284.742881428025;9816.411015339103;10380.273380863267;10386.221033422675;10619.522121504397;10619.840702389316;7548.811279718051;7699.769006929801;7712.38300935935;7739.690412383442;7784.260495252942;8421.831563368822;8490.15098490146;8506.276966457921;8509.36179786531;8514.403991720521;6519.3480651936325;7207.099112610924;7501.174870764103;7522.736704215326;7580.620968951641;7297.927020729725;7377.852442951218;7405.888911666221;7434.553926764412;7458.92541074604;7007.614581198083;7153.570785752871;7157.785266442015;7158.534695244793;7168.569885806868;4979.203327467588;5068.265101560327;5096.725818545364;5159;5195.766190521511;4954.925676114789;4993.226049962262;5048.167052436307;5052.595792336678;5257.2902658140665 14:18:40 INFO - PROCESS | 1667 | |4;prototype.html;301.690766963862;307.22119427251914;311.6618468961177;316.3388517997385;318.83898631191244;4924.950750492494;5036.722980236085;5102.030614183307;5141.768620412081;5182.507661772131;5033.672811267265;5059.873503162423;5193.870153246171;5211.5830733541325;5252.474752524746;3561.92876142477;4009.4587230723914;4026.8590599329027;4032.9798351008276;4040.898977525556;3886.300465916134;4025.29557327468;4035.273650742865;4039.5354534228645;4046.231216068952;3495.2834668892815;3972.7616343019367;4020.6984476164257;4022.7787471689044;4026.8120904333146;3508.5789705235534;3628.092976755811;3964.801759912007;3970.3051965905997;3991.0244429007535;3483.8432270547887;3491.5810102787573;3503.807290599016;3503.9313009532084;3514.2428785607194;4893.486183950705;4936.617747030801;5052.317937078484;5157.461433938148;5191.532762051395;4179.832806687728;4543.273076307808;4577.908441831178;4578.51925547816;4580.7938642761155;5386.649867758583;5442.292501974757;5462.453754624506;5466.480684334982;5661.264035675335;3684.6100793387154;3756.417755247941;3773.9622603773887;3831.386978083521;3841.750286231386;5713.485786279255;5740.425957404268;5741.56460884783;5758.165066065397;5760.308762948431;3295.8352082395786;3640.233422598844;3887.2594820651375;3934.1344904121047;3948.183835435732;3799.639034291738;3953.5453422856162;3974.82113304902;3982.900427489336;3986.980065099714;4528.637708983273;4536.478304994902;4572.451305843254;4590.311453281967;4594.632429405639;5021.372328458942;5140.946106048251;5222.817201397933;5455.754491047913;5541.9168712469345;1101.8126918423886;1266.5440441441074;1304.3174305146445;1309.5285697149018;1312.1471043821593;908.4776253654176;936.4755736787421;939.8026414453042;942.7218970403748;944.5359692476634;4314.225053078556;5072.67027643202;5105.544919697868;5135.870108576108;5160.225966105039;5301.893962120736;5561.443855614406;5565.554755619541;5581.441855814386;5598.944010559842;4770.475247722748;5103.413107492686;5111.667741596784;5116.488351164854;5123.846284611468;2112.7992840680113;2217.545403192342;2438.317271164077;2490.207050923332;2490.7011158660716;4492.080147903888;4762.595179409789;4775.5224477552665;5037.949620503821;5960.523158147338;5617.797855222484;5881.3530511643685;5881.794137205177;5960.552958528127;6051.576389652682;5040.36995375578;5811.418858114239;5935.376785437639;5988.820335389945;6010.4891084258325;5471.358592422337;5644.407337229532;5701.515371193495;5742.224799652161;5752.367239603556;5669.20631111653;5673.482440886677;5693.267231728876;5694.604821818515;5744.3394009687745;5615.298087739033;5847.970760146342;5909.28630697093;5918.915947996041;5946.494547963472;3230.725388342017;3799.5820459749957;3813.7139714520968;3835.6356146166067;3839.6544311012144;4795.688280261772;5680.528969898108;5694.231278777312;5704.771809127588;5705.803265742636;4914.631402644887;5396.3524377075;5484.753186106715;5509.944900550943;5621.747021383966;5209.71346575942;5521.061419558765;5540.944590554042;5566.916496252478;5635.239242702182;4427.933580996223;4445.5109937907455;5168.328117344871;5337.412884582771;5405.972970135124;4513.864584062483;5096.700341413064;5337.733113344395;5362.0616392132;5527.783887544734;5057.69653820772;5377.596680248918;5413.891722165456;5416.320940508455;5430.619856609999 14:18:40 INFO - PROCESS | 1667 | |5;yui.html;592.1274862399905;767.8848172774086;785.4894318692849;802.9317508011819;815.3110621524811;14709.190994495299;15236.162011089385;15289.617759556017;15300.234988250584;15313.923430382847;11975.40122993849;14081.577552673436;14621.488247911326;14638;14639.121652700846;845.5772113943028;847.2036285891273;857.8970523537184;863.063576020019;864.3215076165211;862.3101518784978;864.2481041493909;864.2912811494577;866.5623859950716;866.9913300867005;908.3777612335538;908.4413085952125;909.1862782809415;909.9545022748869;910.1490106750176;901.8196360727815;904.2946501728662;905.5381755304809;905.8369493491169;907.9001309855911;663.9070530125787;664.9767258145941;666.943309818666;670.4736781626449;671.5265737654942;13980.580582582437;14035.298235088205;14036.859631403657;14040.297985100704;14088.788668169986;12153.270803751802;12166;12300.384980751016;12457.813132803016;12492.75014499705;10665.146788256921;10929.398883061429;10990.230683852133;11136.665900023012;11249.662510124628;3682.907927301812;3709.949409780781;3716.869909553153;3856.016715737469;3869.903252418712;116324.41837790841;122283;123064;123827.38086309601;124612;9289.535523223813;9316.720498385059;9359.719208423758;9458.603649201916;9632.036796320312;20495.282665106948;20498.897505512377;20501.897490512452;20520.589588208153;20531.692024619642;7252.311030452099;7628.644833004847;7814.062312522534;7906;7996.320312773363;4196.265653510623;4238.788060596958;4272.145570885836;4286.2284788738325;4392.538783427758;3476.443768996948;3633.000924745716;3634.5093412388987;3636.1455058061865;3636.7272454566014;649.9967500162469;650.5803756576981;660.2539130782282;663.9900401493982;679.0357692077262;599.1551911804335;619.1981384107592;621.7979156773976;624.1418050181001;637.4072112935015;6441.742330306735;8704.216620504058;8715.694950676823;8850.159234872677;8901.688440904667;567.591334239347;603.5805115444812;609.4058293164129;612.6324205476767;612.9724162412703;4218.599233072853;4308.719933204332;4313.568643135724;4345.565443455629;4477.8208871644765;99369;115978;119268;153396.2330188364;158094;112578.87421125847;114225.4288728551;114989;144799;146782;95569.0443095574;105925.47037264919;107612.4619376898;110432.4660774306;117023;9686.660966866126;9700.902990970282;9837.557309921214;9854.605815767289;9877.802443951225;62201.44496387627;62535.43661408501;62567.374326256155;62652.68673656602;64397.034044488435;89216.6617500751;93858.89917155362;95549.52225239108;96076;96642;10995.780084398428;13084.542041028519;13094.672633184247;13165.078444508792;13267.80098298507;14294.285285735463;14331.570052898429;14680.706385872436;15194.392224310905;15239.31423085986;13509.932450337685;13559.86440135586;13969.511067112602;13980.300984950914;14121.576352709435;13044;14008.354883628488;14025.509107181608;14150.504732334319;14202.218877961812;12260.203086799329;12727.490900364252;13154.736905262032;13163.67090822737;13170.07809453329;11261.454825266926;11370.94314528422;11441.14191435629;11450.255733377304;11468.304948337302;11332.660020199408;11418.600348987746;11573.074154067823;11602.6519204424;11652.359120248468 14:18:40 INFO - PROCESS | 1667 | __end_tp_report 14:18:40 INFO - PROCESS | 1667 | __start_cc_report 14:18:40 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1565 14:18:40 INFO - PROCESS | 1667 | __end_cc_report 14:18:40 INFO - PROCESS | 1667 | __startTimestamp1476047920611__endTimestamp 14:18:40 INFO - PROCESS | 1667 | 14:18:40 INFO - PROCESS | 1667 | ------- Summary: start ------- 14:18:40 INFO - PROCESS | 1667 | Number of tests: 6 14:18:40 INFO - PROCESS | 1667 | 14:18:40 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5739.45 Median:6014.10 stddev:640.89 (10.7%) stddev-sans-first:614.82 14:18:40 INFO - PROCESS | 1667 | Values: 3349.3 3354.8 3359.6 3408.6 3420.9 5611.3 5616.4 5693.0 5725.4 5761.0 5659.8 5670.1 5719.5 5724.1 5731.5 4492.6 5088.8 5203.2 5214.1 5281.6 5214.0 5219.2 5220.1 5236.1 5247.1 5089.5 5301.2 5356.9 5360.5 5371.5 4360.3 5156.2 5220.5 5262.8 5291.9 5119.8 5159.4 5175.8 5218.0 5231.9 5563.2 5573.5 5629.1 5766.2 5791.7 6195.2 6197.4 6199.5 6206.6 6240.3 6114.9 6134.8 6170.9 6211.2 6224.7 5772.2 5778.2 5820.4 5824.8 5853.9 6271.6 6304.4 6310.3 6314.9 6321.6 6158.1 6161.3 6162.5 6168.9 6171.8 6115.9 6121.7 6133.1 6158.3 6159.2 6175.0 6196.5 6200.7 6203.0 6206.8 6152.4 6167.4 6204.7 6206.3 6228.0 4834.5 4891.6 4900.9 4946.9 4985.4 4397.7 4419.9 4438.6 4472.8 4508.8 5814.1 5824.5 5832.6 5832.7 5874.9 6115.5 6183.9 6189.2 6194.9 6215.2 5643.0 5711.8 5752.5 5766.4 5776.8 5398.6 5420.2 5424.0 5437.4 5455.5 6316.6 6326.7 6326.8 6328.8 6348.9 6111.8 6156.6 6213.6 6228.4 6260.2 6236.5 6300.1 6315.4 6321.8 6344.2 6149.9 6166.3 6176.8 6179.9 6188.9 6135.5 6163.9 6176.4 6194.6 6241.6 6071.7 6092.4 6116.8 6138.2 6151.9 6106.6 6136.4 6152.9 6156.5 6165.9 5769.0 5970.4 6012.4 6014.1 6059.0 5961.0 6040.0 6101.9 6102.8 6154.9 5802.5 5978.6 5989.1 6017.4 6034.1 14:18:40 INFO - PROCESS | 1667 | 14:18:40 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:68948.77 Median:40721.59 stddev:149697.74 (367.6%) stddev-sans-first:150029.38 14:18:40 INFO - PROCESS | 1667 | Values: 469.1 474.8 493.6 527.7 528.2 33502.3 39239.4 39377.8 39405.8 40712.6 40730.6 41115.0 41160.0 41246.4 41358.4 9646.9 9816.5 9849.2 9860.6 9861.4 9657.8 9751.7 9762.9 9763.6 9772.0 9751.2 9803.2 9816.0 9823.9 9841.3 9758.2 9776.8 9784.0 9793.6 9808.2 8863.9 9468.4 9592.5 9679.3 9707.8 39130.0 39429.6 39453.8 39501.4 39587.0 3262.6 3271.2 3284.9 3464.8 3469.9 29627.0 29798.6 30145.8 30272.7 31365.4 3149.9 3216.9 3219.8 3243.0 3276.1 482462.0 482642.6 483682.6 483854.0 491591.0 54577.2 54708.0 54826.0 54970.0 55006.2 30852.0 32614.7 33661.8 57053.7 57478.1 15826.5 15831.1 16025.4 16056.8 16068.6 12022.3 12040.5 12446.7 12486.0 12531.7 16059.1 18343.4 18407.2 18414.8 18433.2 6633.7 6832.1 7432.0 7600.7 7832.8 819.9 961.8 983.4 1018.3 1046.7 26065.7 27130.8 27569.7 29227.6 29920.9 803.5 996.8 1014.4 1058.4 1077.0 13900.0 14541.6 15038.5 15079.8 15309.5 615531.0 624493.0 625356.0 628746.0 630695.0 54204.2 54704.2 54723.0 54726.0 54775.5 1210.8 1258.2 1259.8 1350.6 1366.6 27654.2 30839.5 32456.8 32693.3 33934.7 55964.4 58603.4 58789.0 58925.0 59061.1 497426.0 517222.0 595770.0 597422.0 598705.0 43329.4 43375.0 43826.8 44198.6 44286.3 38640.8 46949.0 47420.0 47605.8 49382.8 32340.2 40095.4 40143.0 40312.8 41507.4 38763.4 38793.4 39630.0 40747.0 40782.0 37668.4 38109.6 38581.8 38790.6 38972.4 33859.3 34598.3 34810.3 35395.8 35726.5 19015.4 21282.0 25387.4 25448.0 25745.9 14:18:40 INFO - PROCESS | 1667 | 14:18:40 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:33258.81 Median:377.81 stddev:94098.68 (24906.1%) stddev-sans-first:94330.22 14:18:40 INFO - PROCESS | 1667 | Values: 354.1 373.2 373.9 381.7 404.7 15144.8 15356.3 17216.9 20906.2 21376.5 20076.7 20328.3 20332.7 20564.6 21633.0 5757.6 5889.5 5957.5 6292.1 6301.9 6059.0 6170.3 6236.5 6252.7 6258.1 5840.7 6079.2 6114.5 6174.5 6200.8 6127.4 6197.2 6203.9 6207.3 6210.0 5242.2 5556.4 5595.8 5607.9 5619.1 13397.5 13428.7 13433.5 13628.3 13688.5 2132.9 2238.9 2635.5 2810.4 2855.4 9179.3 9490.7 9532.1 10288.5 10581.7 1836.7 1851.6 1853.5 1870.1 1873.3 112909.0 123584.0 125377.0 125548.0 127020.4 11119.2 11308.5 11489.9 11516.4 11756.4 14540.1 14568.5 14582.8 14604.3 14611.6 8187.6 8506.7 8547.4 8635.1 8657.2 8367.4 8394.5 8438.5 8559.0 8562.0 4430.4 4520.4 4528.2 4601.4 4610.6 1647.8 1848.8 1890.3 1910.9 1919.5 827.6 899.9 912.9 967.7 1034.5 19547.6 21855.6 22072.2 22121.0 22253.0 770.5 850.6 883.3 915.4 944.2 10947.2 11346.3 11411.5 11483.9 11501.6 468174.0 551166.0 574956.5 597202.0 602570.0 121351.8 127029.4 127348.6 128151.4 130022.7 961.3 965.6 987.6 989.0 989.9 18373.4 18413.6 18523.8 18960.3 18995.8 47973.8 48819.0 49219.5 54197.0 55530.7 67948.3 69800.0 70603.0 71354.6 72031.3 6861.9 7088.4 7212.1 7222.9 7989.1 12628.4 12848.7 13386.3 13438.5 13569.8 5148.5 5225.5 5267.8 5299.2 11071.2 10244.8 10732.5 10866.5 10961.9 11371.6 11144.3 11454.8 11680.8 11685.1 11818.1 5539.0 7130.8 7139.6 7344.6 7413.9 8851.0 10969.7 11122.5 11238.6 11579.1 14:18:40 INFO - PROCESS | 1667 | 14:18:40 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5723.15 Median:4988.56 stddev:3239.33 (64.9%) stddev-sans-first:3231.99 14:18:40 INFO - PROCESS | 1667 | Values: 1381.8 1563.2 1573.4 1802.9 1942.6 7930.8 8399.1 8672.7 9016.0 9032.7 8854.5 8948.8 8953.3 8959.1 8968.8 2083.3 2372.8 2374.9 2378.6 2379.9 2314.5 2355.7 2359.3 2362.5 2368.9 2328.6 2351.0 2358.1 2363.2 2367.5 2114.9 2192.8 2306.6 2332.3 2342.8 2315.3 2321.6 2322.2 2325.1 2325.8 8778.7 9002.3 9002.7 9005.1 9045.9 5231.1 5455.5 5467.3 5474.8 5477.9 3033.6 3674.1 3755.9 3765.8 3767.1 1064.2 1086.4 1760.3 1830.9 1880.4 11891.0 11942.3 11974.5 11974.6 11982.0 4270.3 4594.5 4639.6 4648.7 4648.9 9220.3 9225.4 9231.0 9231.4 9242.3 5308.2 5315.9 5319.7 5321.6 5329.0 4157.3 4455.1 4636.1 4983.9 4994.1 3183.3 3189.0 3516.5 3717.6 3814.7 1138.4 1145.2 1156.7 1160.1 1164.0 760.9 844.3 864.8 919.5 930.8 4482.4 5856.4 6025.4 6120.3 6155.6 829.7 900.2 957.1 962.6 1003.9 3919.3 4005.9 4010.4 4032.0 4166.4 10769.9 10935.8 11109.1 11156.1 11194.6 11037.2 11041.8 11045.0 11063.7 11066.4 6547.6 6982.7 8241.7 8314.2 8345.0 5099.4 5670.8 5985.6 6002.4 6205.3 8039.4 10117.6 10124.0 10230.5 10284.7 9816.4 10380.3 10386.2 10619.5 10619.8 7548.8 7699.8 7712.4 7739.7 7784.3 8421.8 8490.2 8506.3 8509.4 8514.4 6519.3 7207.1 7501.2 7522.7 7580.6 7297.9 7377.9 7405.9 7434.6 7458.9 7007.6 7153.6 7157.8 7158.5 7168.6 4979.2 5068.3 5096.7 5159.0 5195.8 4954.9 4993.2 5048.2 5052.6 5257.3 14:18:40 INFO - PROCESS | 1667 | 14:18:40 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4478.97 Median:5066.27 stddev:1393.60 (27.5%) stddev-sans-first:1361.78 14:18:40 INFO - PROCESS | 1667 | Values: 301.7 307.2 311.7 316.3 318.8 4925.0 5036.7 5102.0 5141.8 5182.5 5033.7 5059.9 5193.9 5211.6 5252.5 3561.9 4009.5 4026.9 4033.0 4040.9 3886.3 4025.3 4035.3 4039.5 4046.2 3495.3 3972.8 4020.7 4022.8 4026.8 3508.6 3628.1 3964.8 3970.3 3991.0 3483.8 3491.6 3503.8 3503.9 3514.2 4893.5 4936.6 5052.3 5157.5 5191.5 4179.8 4543.3 4577.9 4578.5 4580.8 5386.6 5442.3 5462.5 5466.5 5661.3 3684.6 3756.4 3774.0 3831.4 3841.8 5713.5 5740.4 5741.6 5758.2 5760.3 3295.8 3640.2 3887.3 3934.1 3948.2 3799.6 3953.5 3974.8 3982.9 3987.0 4528.6 4536.5 4572.5 4590.3 4594.6 5021.4 5140.9 5222.8 5455.8 5541.9 1101.8 1266.5 1304.3 1309.5 1312.1 908.5 936.5 939.8 942.7 944.5 4314.2 5072.7 5105.5 5135.9 5160.2 5301.9 5561.4 5565.6 5581.4 5598.9 4770.5 5103.4 5111.7 5116.5 5123.8 2112.8 2217.5 2438.3 2490.2 2490.7 4492.1 4762.6 4775.5 5037.9 5960.5 5617.8 5881.4 5881.8 5960.6 6051.6 5040.4 5811.4 5935.4 5988.8 6010.5 5471.4 5644.4 5701.5 5742.2 5752.4 5669.2 5673.5 5693.3 5694.6 5744.3 5615.3 5848.0 5909.3 5918.9 5946.5 3230.7 3799.6 3813.7 3835.6 3839.7 4795.7 5680.5 5694.2 5704.8 5705.8 4914.6 5396.4 5484.8 5509.9 5621.7 5209.7 5521.1 5540.9 5566.9 5635.2 4427.9 4445.5 5168.3 5337.4 5406.0 4513.9 5096.7 5337.7 5362.1 5527.8 5057.7 5377.6 5413.9 5416.3 5430.6 14:18:40 INFO - PROCESS | 1667 | 14:18:40 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:24401.16 Median:3786.44 stddev:38936.85 (1028.3%) stddev-sans-first:39005.04 14:18:40 INFO - PROCESS | 1667 | Values: 592.1 767.9 785.5 802.9 815.3 14709.2 15236.2 15289.6 15300.2 15313.9 11975.4 14081.6 14621.5 14638.0 14639.1 845.6 847.2 857.9 863.1 864.3 862.3 864.2 864.3 866.6 867.0 908.4 908.4 909.2 910.0 910.1 901.8 904.3 905.5 905.8 907.9 663.9 665.0 666.9 670.5 671.5 13980.6 14035.3 14036.9 14040.3 14088.8 12153.3 12166.0 12300.4 12457.8 12492.8 10665.1 10929.4 10990.2 11136.7 11249.7 3682.9 3709.9 3716.9 3856.0 3869.9 116324.4 122283.0 123064.0 123827.4 124612.0 9289.5 9316.7 9359.7 9458.6 9632.0 20495.3 20498.9 20501.9 20520.6 20531.7 7252.3 7628.6 7814.1 7906.0 7996.3 4196.3 4238.8 4272.1 4286.2 4392.5 3476.4 3633.0 3634.5 3636.1 3636.7 650.0 650.6 660.3 664.0 679.0 599.2 619.2 621.8 624.1 637.4 6441.7 8704.2 8715.7 8850.2 8901.7 567.6 603.6 609.4 612.6 613.0 4218.6 4308.7 4313.6 4345.6 4477.8 99369.0 115978.0 119268.0 153396.2 158094.0 112578.9 114225.4 114989.0 144799.0 146782.0 95569.0 105925.5 107612.5 110432.5 117023.0 9686.7 9700.9 9837.6 9854.6 9877.8 62201.4 62535.4 62567.4 62652.7 64397.0 89216.7 93858.9 95549.5 96076.0 96642.0 10995.8 13084.5 13094.7 13165.1 13267.8 14294.3 14331.6 14680.7 15194.4 15239.3 13509.9 13559.9 13969.5 13980.3 14121.6 13044.0 14008.4 14025.5 14150.5 14202.2 12260.2 12727.5 13154.7 13163.7 13170.1 11261.5 11370.9 11441.1 11450.3 11468.3 11332.7 11418.6 11573.1 11602.7 11652.4 14:18:40 INFO - PROCESS | 1667 | -------- Summary: end -------- 14:18:40 INFO - PROCESS | 1667 | 14:18:41 INFO - TEST-INFO | 1667: exit 0 14:18:41 INFO - TEST-OK | dromaeo_css | took 1118215ms 14:18:41 INFO - TEST-START | kraken 14:18:41 INFO - operating with platform_type : mac_ 14:18:41 INFO - Initialising browser for kraken test... 14:18:41 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpBJSEHR/profile http://localhost:49271/getInfo.html) 14:18:43 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 14:18:43 INFO - PROCESS | 1672 | colorDepth:24 14:18:43 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 14:18:43 INFO - PROCESS | 1672 | __metrics 14:18:43 INFO - PROCESS | 1672 | 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" 14:18:43 INFO - PROCESS | 1672 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:18:44 INFO - TEST-INFO | 1672: exit 0 14:18:44 INFO - Browser initialized. 14:18:44 INFO - Running cycle 1/1 for kraken test... 14:18:44 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpBJSEHR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:18:56 INFO - PROCESS | 1673 | RSS: Main: 235208704 14:18:56 INFO - PROCESS | 1673 | 14:18:59 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 14:19:00 INFO - PROCESS | 1673 | RSS: Main: 294461440 14:19:00 INFO - PROCESS | 1673 | 14:19:03 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 14:19:03 INFO - PROCESS | 1673 | RSS: Main: 294010880 14:19:03 INFO - PROCESS | 1673 | 14:19:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 14:19:07 INFO - PROCESS | 1673 | RSS: Main: 385445888 14:19:07 INFO - PROCESS | 1673 | 14:19:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 14:19:10 INFO - PROCESS | 1673 | RSS: Main: 286908416 14:19:10 INFO - PROCESS | 1673 | 14:19:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:19:13 INFO - PROCESS | 1673 | RSS: Main: 291008512 14:19:13 INFO - PROCESS | 1673 | 14:19:15 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 14:19:16 INFO - PROCESS | 1673 | RSS: Main: 327593984 14:19:16 INFO - PROCESS | 1673 | 14:19:19 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 14:19:19 INFO - PROCESS | 1673 | RSS: Main: 328273920 14:19:19 INFO - PROCESS | 1673 | 14:19:22 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 14:19:23 INFO - PROCESS | 1673 | RSS: Main: 326590464 14:19:23 INFO - PROCESS | 1673 | 14:19:24 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:19:25 INFO - PROCESS | 1673 | RSS: Main: 301400064 14:19:25 INFO - PROCESS | 1673 | 14:19:27 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:19:27 INFO - PROCESS | 1673 | RSS: Main: 307433472 14:19:27 INFO - PROCESS | 1673 | 14:19:30 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:19:30 INFO - PROCESS | 1673 | RSS: Main: 309780480 14:19:30 INFO - PROCESS | 1673 | 14:19:33 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:19:34 INFO - PROCESS | 1673 | RSS: Main: 319377408 14:19:34 INFO - PROCESS | 1673 | 14:19:37 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:19:38 INFO - PROCESS | 1673 | RSS: Main: 318664704 14:19:38 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 14:19:40 INFO - PROCESS | 1673 | RSS: Main: 362528768 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | __start_tp_report 14:19:40 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 14:19:40 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 14:19:40 INFO - PROCESS | 1673 | |i|pagename|runs| 14:19:40 INFO - PROCESS | 1673 | |0;ai-astar;155;165;143;114;112;113;114;114;113;113 14:19:40 INFO - PROCESS | 1673 | |1;audio-beat-detection;173;141;116;150;113;114;115;115;144;112 14:19:40 INFO - PROCESS | 1673 | |2;audio-dft;182;184;183;183;184;182;184;185;180;182 14:19:40 INFO - PROCESS | 1673 | |3;audio-fft;79;59;60;87;61;60;60;58;82;57 14:19:40 INFO - PROCESS | 1673 | |4;audio-oscillator;82;71;91;72;69;69;93;67;68;90 14:19:40 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;86;83;87;82;86;83;87;83;93;83 14:19:40 INFO - PROCESS | 1673 | |6;imaging-darkroom;95;95;95;96;95;95;96;95;94;95 14:19:40 INFO - PROCESS | 1673 | |7;imaging-desaturate;86;84;83;83;83;85;83;84;84;84 14:19:40 INFO - PROCESS | 1673 | |8;json-parse-financial;56;53;53;52;54;54;53;52;55;55 14:19:40 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;65;75;77;71;73;70;70;73;69;72 14:19:40 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;87;90;87;86;90;99;99;84;87;86 14:19:40 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;150;148;141;143;172;159;156;177;206;151 14:19:40 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;173;173;172;174;174;236;213;173;172;171 14:19:40 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;69;69;70;72;78;86;78;69;67;68 14:19:40 INFO - PROCESS | 1673 | __end_tp_report 14:19:40 INFO - PROCESS | 1673 | __start_cc_report 14:19:40 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,2016 14:19:40 INFO - PROCESS | 1673 | __end_cc_report 14:19:40 INFO - PROCESS | 1673 | __startTimestamp1476047980347__endTimestamp 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | ------- Summary: start ------- 14:19:40 INFO - PROCESS | 1673 | Number of tests: 14 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:125.60 Median:114.00 stddev:20.51 (18.0%) stddev-sans-first:18.79 14:19:40 INFO - PROCESS | 1673 | Values: 155.0 165.0 143.0 114.0 112.0 113.0 114.0 114.0 113.0 113.0 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:129.30 Median:128.50 stddev:21.28 (16.6%) stddev-sans-first:15.63 14:19:40 INFO - PROCESS | 1673 | Values: 173.0 141.0 116.0 150.0 113.0 114.0 115.0 115.0 144.0 112.0 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.50 stddev:1.45 (0.8%) stddev-sans-first:1.50 14:19:40 INFO - PROCESS | 1673 | Values: 182.0 184.0 183.0 183.0 184.0 182.0 184.0 185.0 180.0 182.0 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:66.30 Median:60.50 stddev:11.51 (19.0%) stddev-sans-first:11.25 14:19:40 INFO - PROCESS | 1673 | Values: 79.0 59.0 60.0 87.0 61.0 60.0 60.0 58.0 82.0 57.0 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:77.00 stddev:10.62 (13.8%) stddev-sans-first:11.12 14:19:40 INFO - PROCESS | 1673 | Values: 82.0 71.0 91.0 72.0 69.0 69.0 93.0 67.0 68.0 90.0 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.00 stddev:3.30 (3.8%) stddev-sans-first:3.49 14:19:40 INFO - PROCESS | 1673 | Values: 86.0 83.0 87.0 82.0 86.0 83.0 87.0 83.0 93.0 83.0 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 14:19:40 INFO - PROCESS | 1673 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 96.0 95.0 94.0 95.0 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:83.90 Median:84.00 stddev:0.99 (1.2%) stddev-sans-first:0.71 14:19:40 INFO - PROCESS | 1673 | Values: 86.0 84.0 83.0 83.0 83.0 85.0 83.0 84.0 84.0 84.0 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:1.34 (2.5%) stddev-sans-first:1.13 14:19:40 INFO - PROCESS | 1673 | Values: 56.0 53.0 53.0 52.0 54.0 54.0 53.0 52.0 55.0 55.0 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.50 stddev:3.34 (4.6%) stddev-sans-first:2.59 14:19:40 INFO - PROCESS | 1673 | Values: 65.0 75.0 77.0 71.0 73.0 70.0 70.0 73.0 69.0 72.0 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:88.50 stddev:5.32 (6.0%) stddev-sans-first:5.56 14:19:40 INFO - PROCESS | 1673 | Values: 87.0 90.0 87.0 86.0 90.0 99.0 99.0 84.0 87.0 86.0 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:160.30 Median:157.50 stddev:19.83 (12.6%) stddev-sans-first:20.68 14:19:40 INFO - PROCESS | 1673 | Values: 150.0 148.0 141.0 143.0 172.0 159.0 156.0 177.0 206.0 151.0 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.10 Median:173.50 stddev:22.50 (13.0%) stddev-sans-first:23.57 14:19:40 INFO - PROCESS | 1673 | Values: 173.0 173.0 172.0 174.0 174.0 236.0 213.0 173.0 172.0 171.0 14:19:40 INFO - PROCESS | 1673 | 14:19:40 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:71.00 stddev:6.11 (8.6%) stddev-sans-first:6.34 14:19:40 INFO - PROCESS | 1673 | Values: 69.0 69.0 70.0 72.0 78.0 86.0 78.0 69.0 67.0 68.0 14:19:40 INFO - PROCESS | 1673 | -------- Summary: end -------- 14:19:40 INFO - PROCESS | 1673 | 14:19:41 INFO - TEST-INFO | 1673: exit 0 14:19:41 INFO - TEST-OK | kraken | took 60044ms 14:19:41 INFO - SUITE-END | took 1178s 14:19:41 INFO - Completed test suite (00:19:38) 14:19:41 INFO - javascript benchmark 14:19:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3349.2966477039827, 3354.7819391739536, 3359.630440651528, 3408.556887604611, 3420.880269190579, 5611.270534830466, 5616.41027692092, 5692.971535142318, 5725.427457254273, 5761.049426844571, 5659.773609055633, 5670.149477578365, 5719.513841323493, 5724.055530837417, 5731.541476681866, 4492.640588752892, 5088.77100530477, 5203.245529398235, 5214.139666954967, 5281.63028587999, 5213.973930130363, 5219.217117432378, 5220.138677118271, 5236.057509648262, 5247.081760691864, 5089.491050894918, 5301.231321458406, 5356.892862142755, 5360.490753378443, 5371.462853714617, 4360.302351623725, 5156.1750119981, 5220.530152286313, 5262.789488420459, 5291.947080529184, 5119.769610367543, 5159.380874295071, 5175.844724658266, 5218.0, 5231.921521177185, 5563.248961390201, 5573.526250268732, 5629.127485239795, 5766.192733017381, 5791.681457519835, 6195.1946246988055, 6197.442230199304, 6199.535034872402, 6206.565540412173, 6240.31356550779, 6114.908276375858, 6134.754609815602, 6170.938290617081, 6211.16149319845, 6224.688765561704, 5772.163036359704, 5778.162166485878, 5820.447057529528, 5824.767009319579, 5853.88292234153, 6271.560990730696, 6304.401081897213, 6310.274318453345, 6314.873702525923, 6321.620702757849, 6158.137860699506, 6161.260648722182, 6162.476189523851, 6168.907466387918, 6171.814845554641, 6115.909724157157, 6121.724522396503, 6133.1413602095745, 6158.322584515699, 6159.23009623797, 6174.9691251544355, 6196.47329976948, 6200.720967556471, 6203.0, 6206.751729930843, 6152.352457473413, 6167.414095660905, 6204.689765511796, 6206.255249370104, 6228.03465462854, 4834.468208497063, 4891.608671306287, 4900.926486102711, 4946.851594452172, 4985.426675932242, 4397.714148580332, 4419.911601768011, 4438.578335058164, 4472.821087156477, 4508.842190523316, 5814.069748840166, 5824.504917082011, 5832.620879642895, 5832.737526811304, 5874.941250587525, 6115.541334399938, 6183.876322473526, 6189.164462797555, 6194.907076393858, 6215.160953271341, 5642.971785141048, 5711.8000869969355, 5752.482276595127, 5766.394528574523, 5776.797812076557, 5398.6220964532895, 5420.214068960057, 5423.972880135574, 5437.401885792559, 5455.454454554513, 6316.557840951089, 6326.683665816783, 6326.841828954313, 6328.778492752732, 6348.9047664286, 6111.755529778759, 6156.569040167145, 6213.565050446426, 6228.408301211378, 6260.248770147611, 6236.532260080603, 6300.149479820349, 6315.400036996662, 6321.810345689637, 6344.238691357066, 6149.907751383822, 6166.321704612579, 6176.845578860564, 6179.938200618116, 6188.938110618837, 6135.478484328882, 6163.9383606163365, 6176.444120029039, 6194.566380353332, 6241.594296370721, 6071.726772295169, 6092.360302168208, 6116.822755456359, 6138.235132557229, 6151.892659321186, 6106.564957235135, 6136.392033385704, 6152.876942461216, 6156.538259630456, 6165.876682466236, 5769.048107062287, 5970.402959703995, 6012.428819262163, 6014.097885317237, 6058.969705151446, 5961.0, 6039.969800150971, 6101.877962440815, 6102.816915492543, 6154.938450615616, 5802.535797136304, 5978.641281523122, 5989.071693887455, 6017.368176341596, 6034.064719968412], "value": 5697.982467482482, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.14381254211065, 474.7626186906547, 493.565662217249, 527.688663688424, 528.2498851630684, 33502.32995340095, 39239.41140882889, 39377.803110984474, 39405.8029709851, 40712.59287407125, 40730.59269407305, 41115.0, 41159.971000724996, 41246.38130428046, 41358.37962430566, 9646.903530964672, 9816.50917454128, 9849.21206303494, 9860.605575776995, 9861.359011664255, 9657.758556036086, 9751.707448776513, 9762.853557196648, 9763.609455621767, 9772.0, 9751.219902407825, 9803.215742740564, 9815.950920245388, 9823.852642210373, 9841.311108222428, 9758.219342452623, 9776.755581110458, 9784.02159784023, 9793.6082556698, 9808.16630586401, 8863.911360886374, 9468.431894086378, 9592.472414017226, 9679.274054445874, 9707.805843883083, 39130.02174945621, 39429.60570394317, 39453.80273098645, 39501.40747888784, 39587.0, 3262.559554460141, 3271.1658527075683, 3284.8521816518314, 3464.7574669773126, 3469.8959031229106, 29627.0, 29798.55302170469, 30145.849270753726, 30272.697273027206, 31365.37269254602, 3149.9370012599848, 3216.8713251469912, 3219.8390080495883, 3242.951355729666, 3276.082696844863, 482462.0, 482642.58678706735, 483682.58158709336, 483854.0, 491591.0, 54577.181342279895, 54708.0, 54826.0, 54970.0, 55006.17490737642, 30852.0, 32614.673853261163, 33661.83169084188, 57053.71473142608, 57478.137827932616, 15826.525204243891, 15831.129287889047, 16025.358985640676, 16056.75914861278, 16068.598285042968, 12022.27866328023, 12040.458179381949, 12446.688832779253, 12486.001119910567, 12531.74936501283, 16059.116748578706, 18343.449696509128, 18407.171677274553, 18414.815851841577, 18433.170507326933, 6633.668316584151, 6832.0776695146515, 7431.99668044807, 7600.695972161162, 7832.765017049498, 819.8852160697508, 961.7932144588947, 983.3755565216139, 1018.277023313441, 1046.6546039806844, 26065.73934260671, 27130.779114939876, 27569.724302756716, 29227.638627046268, 29920.850395747882, 803.5178892664354, 996.835522138839, 1014.4217795856291, 1058.433737950193, 1076.9730756731144, 13900.009959167333, 14541.563753087425, 15038.473653422077, 15079.849201508065, 15309.540713778604, 615531.0, 624493.0, 625356.0, 628746.0, 630695.0, 54204.18693719598, 54704.17943730847, 54723.0, 54726.0, 54775.452245477034, 1210.806270996654, 1258.1507482449492, 1259.785836407813, 1350.581319790868, 1366.5763613279914, 27654.170374888785, 30839.537406938463, 32456.83771581127, 32693.34613307768, 33934.66065339414, 55964.44035559592, 58603.41396585979, 58789.0, 58925.0, 59061.11408328964, 497426.0, 517222.0, 595770.0, 597422.0, 598705.0, 43329.35005974976, 43375.0, 43826.78086609547, 44198.55801442073, 44286.335704965095, 38640.806795966964, 46949.0, 47420.0, 47605.76197119131, 49382.75308623434, 32340.191495212806, 40095.3985690209, 40143.0, 40312.79843600763, 41507.37738933979, 38763.41854872123, 38793.418098729104, 39630.009249768984, 40747.0, 40782.0, 37668.43497347597, 38109.61890381061, 38581.807090965485, 38790.6120938787, 38972.41541376825, 33859.32281354408, 34598.30803383968, 34810.30379392449, 35395.82302088473, 35726.46410303796, 19015.42953711391, 21282.0, 25387.36531586725, 25448.0, 25745.871270643525], "value": 19760.651737797656, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.13590838354395, 373.23859326972996, 373.93269211541923, 381.69464428457235, 404.71063189819273, 15144.848551514482, 15356.308966096525, 17216.91391543042, 20906.16375344988, 21376.465588360297, 20076.69884951727, 20328.28850990212, 20332.69500957487, 20564.588708225863, 21633.0, 5757.596968212227, 5889.499392551616, 5957.510622344414, 6292.0876472911405, 6301.873962520747, 6058.969705151468, 6170.290416602081, 6236.532260080489, 6252.687365631723, 6258.06129080637, 5840.737166827503, 6079.179310793031, 6114.510839132859, 6174.536909731765, 6200.751969921198, 6127.35662755411, 6197.225346831646, 6203.937960620381, 6207.25512938448, 6209.950716263622, 5242.239875218072, 5556.388797232301, 5595.804146854881, 5607.915881261784, 5619.129034999582, 13397.464101435931, 13428.731425371536, 13433.529826456124, 13628.318584070757, 13688.452461901512, 2132.936011919645, 2238.865668059905, 2635.472905418924, 2810.3861663999774, 2855.4431885782283, 9179.311551633655, 9490.667826626104, 9532.094451027142, 10288.537015834305, 10581.73545661357, 1836.7336736173313, 1851.5556266496078, 1853.5458812591, 1870.093004892626, 1873.325602782997, 112909.0, 123584.0, 125377.0, 125548.0, 127020.36489817491, 11119.166062545342, 11308.491117899714, 11489.885101148882, 11516.424178791027, 11756.35340056288, 14540.127592344494, 14568.49010284635, 14582.781258281135, 14604.269786510631, 14611.561653150422, 8187.631556579968, 8506.702265420681, 8547.35894807892, 8635.050144484101, 8657.177568131017, 8367.414281000272, 8394.496330220207, 8438.45150065256, 8558.957205213934, 8561.957190214009, 4430.401895744098, 4520.389747384127, 4528.162289976365, 4601.401817763736, 4610.608098311681, 1647.7857878475722, 1848.7798053284766, 1890.3100368365722, 1910.9140088695765, 1919.4548388547075, 827.5737994932615, 899.9245901148196, 912.9413173294179, 967.7338731848657, 1034.5086084109987, 19547.609047818965, 21855.562888742137, 22072.2274720384, 22121.004554795072, 22253.0, 770.4760762681318, 850.6214734443237, 883.2977782662798, 915.4004127296631, 944.197432182653, 10947.233693641369, 11346.319220846775, 11411.48648310828, 11483.942580287212, 11501.597444089417, 468174.0, 551166.0, 574956.5026098448, 597202.0, 602570.0, 121351.78648213405, 127029.36485317514, 127348.6325683712, 128151.35924320505, 130022.69977300106, 961.2646325560952, 965.584798536636, 987.5704068730125, 988.9752756181153, 989.9059589339001, 18373.448796536126, 18413.631727365646, 18523.795953262994, 18960.336388226347, 18995.810041899404, 47973.760131199124, 48818.99999999858, 49219.50780492149, 54197.0, 55530.72234638801, 67948.32051679419, 69800.0, 70603.0, 71354.64322678353, 72031.27968720246, 6861.867791814295, 7088.397486213729, 7212.098487689039, 7222.89165662505, 7989.121196668246, 12628.431720572413, 12848.678783030498, 13386.26375549354, 13438.46246150143, 13569.79645305341, 5148.542475226355, 5225.503577160163, 5267.841964741064, 5299.152135658276, 11071.187626496154, 10244.846327304947, 10732.463376831283, 10866.511007004545, 10961.945190273998, 11371.601993930173, 11144.275622084539, 11454.82817757751, 11680.824787628362, 11685.065194784567, 11818.054555635701, 5538.972305138448, 7130.821729456805, 7139.571625702474, 7344.596047217455, 7413.925860741324, 8850.955745221232, 10969.725756856142, 11122.499487523242, 11238.550457981915, 11579.073674105883], "value": 9171.690552956543, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.8479967203612, 1563.1871426858038, 1573.3785154863829, 1802.8828126171798, 1942.5629233422474, 7930.7620771376805, 8399.11809260028, 8672.653093876253, 9015.963164236115, 9032.729018129467, 8854.468731876075, 8948.776280592989, 8953.328500362482, 8959.059298773633, 8968.775780605472, 2083.3437467197823, 2372.810175185981, 2374.905003799846, 2378.63131214662, 2379.9048038078545, 2314.456102815837, 2355.693759811222, 2359.2686267257204, 2362.527494501098, 2368.917087901924, 2328.5924963131383, 2350.9529809403803, 2358.1039205102124, 2363.2083252110565, 2367.5146594947996, 2114.9365519034454, 2192.8487544039426, 2306.606227242324, 2332.3119679694532, 2342.847714898526, 2315.3169814904645, 2321.6285394336824, 2322.210448447519, 2325.104834638659, 2325.7557956414503, 8778.692745753868, 9002.279817614575, 9002.729918102468, 9005.099490051009, 9045.864312035259, 5231.110711179071, 5455.481729235756, 5467.289252397202, 5474.753636086346, 5477.863053423696, 3033.620797400325, 3674.1365779041903, 3755.9248815023548, 3765.7740535567955, 3767.077066118791, 1064.164630764846, 1086.3916206924148, 1760.287083731091, 1830.9359172429033, 1880.4358692392227, 11890.989265912407, 11942.343171125585, 11974.52101915931, 11974.64076077719, 11982.0, 4270.252705776476, 4594.471635761864, 4639.582437580567, 4648.651351148677, 4648.907021859543, 9220.308476864262, 9225.354225204172, 9231.030741772152, 9231.399959002778, 9242.306826987868, 5308.23030660552, 5315.920261196085, 5319.7340132993195, 5321.627486075938, 5329.040772660881, 4157.334826427757, 4455.1312494064, 4636.119137363958, 4983.90032199354, 4994.076095922266, 3183.3474137801695, 3188.9681103188673, 3516.542849429609, 3717.628237176315, 3814.732968692165, 1138.408027825526, 1145.1869172887177, 1156.670348950545, 1160.118310084325, 1163.9825402618967, 760.8858671199254, 844.3329769482041, 864.8227113441856, 919.5310391700281, 930.7859192385654, 4482.4172857528965, 5856.385079566584, 6025.367336429699, 6120.326764055951, 6155.599886007485, 829.7261903571807, 900.1853322742928, 957.1098878043347, 962.5957098018849, 1003.8996100390049, 3919.3337132686943, 4005.859794907164, 4010.3783913493453, 4031.9798401007806, 4166.354215096665, 10769.9461502692, 10935.781284374269, 11109.111271098292, 11156.051735602403, 11194.552217911356, 11037.227394082498, 11041.83437248442, 11045.0, 11063.668089957315, 11066.39134847575, 6547.574407663582, 6982.720691172398, 8241.711540096068, 8314.168583141758, 8344.958275208584, 5099.413567439792, 5670.773169073191, 5985.551083668829, 6002.429769171922, 6205.2553693557065, 8039.397045221749, 10117.59529618807, 10124.0, 10230.539625716712, 10284.742881428025, 9816.411015339103, 10380.273380863267, 10386.221033422675, 10619.522121504397, 10619.840702389316, 7548.811279718051, 7699.769006929801, 7712.38300935935, 7739.690412383442, 7784.260495252942, 8421.831563368822, 8490.15098490146, 8506.276966457921, 8509.36179786531, 8514.403991720521, 6519.3480651936325, 7207.099112610924, 7501.174870764103, 7522.736704215326, 7580.620968951641, 7297.927020729725, 7377.852442951218, 7405.888911666221, 7434.553926764412, 7458.92541074604, 7007.614581198083, 7153.570785752871, 7157.785266442015, 7158.534695244793, 7168.569885806868, 4979.203327467588, 5068.265101560327, 5096.725818545364, 5159.0, 5195.766190521511, 4954.925676114789, 4993.226049962262, 5048.167052436307, 5052.595792336678, 5257.2902658140665], "value": 4604.979313436598, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.690766963862, 307.22119427251914, 311.6618468961177, 316.3388517997385, 318.83898631191244, 4924.950750492494, 5036.722980236085, 5102.030614183307, 5141.768620412081, 5182.507661772131, 5033.672811267265, 5059.873503162423, 5193.870153246171, 5211.5830733541325, 5252.474752524746, 3561.92876142477, 4009.4587230723914, 4026.8590599329027, 4032.9798351008276, 4040.898977525556, 3886.300465916134, 4025.29557327468, 4035.273650742865, 4039.5354534228645, 4046.231216068952, 3495.2834668892815, 3972.7616343019367, 4020.6984476164257, 4022.7787471689044, 4026.8120904333146, 3508.5789705235534, 3628.092976755811, 3964.801759912007, 3970.3051965905997, 3991.0244429007535, 3483.8432270547887, 3491.5810102787573, 3503.807290599016, 3503.9313009532084, 3514.2428785607194, 4893.486183950705, 4936.617747030801, 5052.317937078484, 5157.461433938148, 5191.532762051395, 4179.832806687728, 4543.273076307808, 4577.908441831178, 4578.51925547816, 4580.7938642761155, 5386.649867758583, 5442.292501974757, 5462.453754624506, 5466.480684334982, 5661.264035675335, 3684.6100793387154, 3756.417755247941, 3773.9622603773887, 3831.386978083521, 3841.750286231386, 5713.485786279255, 5740.425957404268, 5741.56460884783, 5758.165066065397, 5760.308762948431, 3295.8352082395786, 3640.233422598844, 3887.2594820651375, 3934.1344904121047, 3948.183835435732, 3799.639034291738, 3953.5453422856162, 3974.82113304902, 3982.900427489336, 3986.980065099714, 4528.637708983273, 4536.478304994902, 4572.451305843254, 4590.311453281967, 4594.632429405639, 5021.372328458942, 5140.946106048251, 5222.817201397933, 5455.754491047913, 5541.9168712469345, 1101.8126918423886, 1266.5440441441074, 1304.3174305146445, 1309.5285697149018, 1312.1471043821593, 908.4776253654176, 936.4755736787421, 939.8026414453042, 942.7218970403748, 944.5359692476634, 4314.225053078556, 5072.67027643202, 5105.544919697868, 5135.870108576108, 5160.225966105039, 5301.893962120736, 5561.443855614406, 5565.554755619541, 5581.441855814386, 5598.944010559842, 4770.475247722748, 5103.413107492686, 5111.667741596784, 5116.488351164854, 5123.846284611468, 2112.7992840680113, 2217.545403192342, 2438.317271164077, 2490.207050923332, 2490.7011158660716, 4492.080147903888, 4762.595179409789, 4775.5224477552665, 5037.949620503821, 5960.523158147338, 5617.797855222484, 5881.3530511643685, 5881.794137205177, 5960.552958528127, 6051.576389652682, 5040.36995375578, 5811.418858114239, 5935.376785437639, 5988.820335389945, 6010.4891084258325, 5471.358592422337, 5644.407337229532, 5701.515371193495, 5742.224799652161, 5752.367239603556, 5669.20631111653, 5673.482440886677, 5693.267231728876, 5694.604821818515, 5744.3394009687745, 5615.298087739033, 5847.970760146342, 5909.28630697093, 5918.915947996041, 5946.494547963472, 3230.725388342017, 3799.5820459749957, 3813.7139714520968, 3835.6356146166067, 3839.6544311012144, 4795.688280261772, 5680.528969898108, 5694.231278777312, 5704.771809127588, 5705.803265742636, 4914.631402644887, 5396.3524377075, 5484.753186106715, 5509.944900550943, 5621.747021383966, 5209.71346575942, 5521.061419558765, 5540.944590554042, 5566.916496252478, 5635.239242702182, 4427.933580996223, 4445.5109937907455, 5168.328117344871, 5337.412884582771, 5405.972970135124, 4513.864584062483, 5096.700341413064, 5337.733113344395, 5362.0616392132, 5527.783887544734, 5057.69653820772, 5377.596680248918, 5413.891722165456, 5416.320940508455, 5430.619856609999], "value": 4035.2715565911103, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.1274862399905, 767.8848172774086, 785.4894318692849, 802.9317508011819, 815.3110621524811, 14709.190994495299, 15236.162011089385, 15289.617759556017, 15300.234988250584, 15313.923430382847, 11975.40122993849, 14081.577552673436, 14621.488247911326, 14638.0, 14639.121652700846, 845.5772113943028, 847.2036285891273, 857.8970523537184, 863.063576020019, 864.3215076165211, 862.3101518784978, 864.2481041493909, 864.2912811494577, 866.5623859950716, 866.9913300867005, 908.3777612335538, 908.4413085952125, 909.1862782809415, 909.9545022748869, 910.1490106750176, 901.8196360727815, 904.2946501728662, 905.5381755304809, 905.8369493491169, 907.9001309855911, 663.9070530125787, 664.9767258145941, 666.943309818666, 670.4736781626449, 671.5265737654942, 13980.580582582437, 14035.298235088205, 14036.859631403657, 14040.297985100704, 14088.788668169986, 12153.270803751802, 12166.0, 12300.384980751016, 12457.813132803016, 12492.75014499705, 10665.146788256921, 10929.398883061429, 10990.230683852133, 11136.665900023012, 11249.662510124628, 3682.907927301812, 3709.949409780781, 3716.869909553153, 3856.016715737469, 3869.903252418712, 116324.41837790841, 122283.0, 123064.0, 123827.38086309601, 124612.0, 9289.535523223813, 9316.720498385059, 9359.719208423758, 9458.603649201916, 9632.036796320312, 20495.282665106948, 20498.897505512377, 20501.897490512452, 20520.589588208153, 20531.692024619642, 7252.311030452099, 7628.644833004847, 7814.062312522534, 7906.0, 7996.320312773363, 4196.265653510623, 4238.788060596958, 4272.145570885836, 4286.2284788738325, 4392.538783427758, 3476.443768996948, 3633.000924745716, 3634.5093412388987, 3636.1455058061865, 3636.7272454566014, 649.9967500162469, 650.5803756576981, 660.2539130782282, 663.9900401493982, 679.0357692077262, 599.1551911804335, 619.1981384107592, 621.7979156773976, 624.1418050181001, 637.4072112935015, 6441.742330306735, 8704.216620504058, 8715.694950676823, 8850.159234872677, 8901.688440904667, 567.591334239347, 603.5805115444812, 609.4058293164129, 612.6324205476767, 612.9724162412703, 4218.599233072853, 4308.719933204332, 4313.568643135724, 4345.565443455629, 4477.8208871644765, 99369.0, 115978.0, 119268.0, 153396.2330188364, 158094.0, 112578.87421125847, 114225.4288728551, 114989.0, 144799.0, 146782.0, 95569.0443095574, 105925.47037264919, 107612.4619376898, 110432.4660774306, 117023.0, 9686.660966866126, 9700.902990970282, 9837.557309921214, 9854.605815767289, 9877.802443951225, 62201.44496387627, 62535.43661408501, 62567.374326256155, 62652.68673656602, 64397.034044488435, 89216.6617500751, 93858.89917155362, 95549.52225239108, 96076.0, 96642.0, 10995.780084398428, 13084.542041028519, 13094.672633184247, 13165.078444508792, 13267.80098298507, 14294.285285735463, 14331.570052898429, 14680.706385872436, 15194.392224310905, 15239.31423085986, 13509.932450337685, 13559.86440135586, 13969.511067112602, 13980.300984950914, 14121.576352709435, 13044.0, 14008.354883628488, 14025.509107181608, 14150.504732334319, 14202.218877961812, 12260.203086799329, 12727.490900364252, 13154.736905262032, 13163.67090822737, 13170.07809453329, 11261.454825266926, 11370.94314528422, 11441.14191435629, 11450.255733377304, 11468.304948337302, 11332.660020199408, 11418.600348987746, 11573.074154067823, 11602.6519204424, 11652.359120248468], "value": 7674.358835582437, "unit": "score"}], "name": "dromaeo_css", "value": 7267.080447054382, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 165.0, 143.0, 114.0, 112.0, 113.0, 114.0, 114.0, 113.0, 113.0], "value": 125.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 141.0, 116.0, 150.0, 113.0, 114.0, 115.0, 115.0, 144.0, 112.0], "value": 129.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 183.0, 183.0, 184.0, 182.0, 184.0, 185.0, 180.0, 182.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 60.0, 87.0, 61.0, 60.0, 60.0, 58.0, 82.0, 57.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 91.0, 72.0, 69.0, 69.0, 93.0, 67.0, 68.0, 90.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 87.0, 82.0, 86.0, 83.0, 87.0, 83.0, 93.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 94.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 83.0, 83.0, 83.0, 85.0, 83.0, 84.0, 84.0, 84.0], "value": 83.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 53.0, 52.0, 54.0, 54.0, 53.0, 52.0, 55.0, 55.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 77.0, 71.0, 73.0, 70.0, 70.0, 73.0, 69.0, 72.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 87.0, 86.0, 90.0, 99.0, 99.0, 84.0, 87.0, 86.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 141.0, 143.0, 172.0, 159.0, 156.0, 177.0, 206.0, 151.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 172.0, 174.0, 174.0, 236.0, 213.0, 173.0, 172.0, 171.0], "value": 183.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 72.0, 78.0, 86.0, 78.0, 69.0, 67.0, 68.0], "value": 72.6, "unit": "score"}], "name": "kraken", "value": 1476.3, "alertThreshold": 2.0}]} 14:19:41 INFO - Return code: 0 14:19:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:19:41 INFO - # TBPL SUCCESS # 14:19:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:19:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:19:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:19:41 INFO - 2016-10-09 14:19:41.689 screenresolution[1677:13097] starting screenresolution argv=screenresolution get 14:19:41 INFO - 2016-10-09 14:19:41.692 screenresolution[1677:13097] Display 0: 1600x1200x32@60 14:19:41 INFO - 2016-10-09 14:19:41.698 screenresolution[1678:13100] starting screenresolution argv=screenresolution list 14:19:41 INFO - Available Modes on Display 0 14:19:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:19:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:19:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:19:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:19:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:19:41 INFO - Intel Iris: 14:19:41 INFO - Chipset Model: Intel Iris 14:19:41 INFO - Type: GPU 14:19:41 INFO - Bus: Built-In 14:19:41 INFO - VRAM (Dynamic, Max): 1536 MB 14:19:41 INFO - Vendor: Intel (0x8086) 14:19:41 INFO - Device ID: 0x0a2e 14:19:41 INFO - Revision ID: 0x0009 14:19:41 INFO - Displays: 14:19:41 INFO - Display: 14:19:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:19:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:19:41 INFO - Display Serial Number: 1600x1200 60 14:19:41 INFO - Main Display: Yes 14:19:41 INFO - Mirror: Off 14:19:41 INFO - Online: Yes 14:19:41 INFO - Rotation: Supported 14:19:41 INFO - Return code: 0 14:19:41 INFO - Running post-action listener: _resource_record_post_action 14:19:41 INFO - [mozharness: 2016-10-09 21:19:41.935292Z] Finished run-tests step (success) 14:19:41 INFO - Running post-run listener: _resource_record_post_run 14:19:41 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 31403008; Write bytes: 708383744; Read time: 200; Write time: 1945 14:19:41 INFO - TinderboxPrint: CPU usage
53.5% 14:19:41 INFO - TinderboxPrint: I/O read bytes / time
31,403,008 / 200 14:19:41 INFO - TinderboxPrint: I/O write bytes / time
708,383,744 / 1,945 14:19:41 INFO - TinderboxPrint: CPU idle
2,257.6 (46.5%) 14:19:41 INFO - TinderboxPrint: CPU system
969.2 (20.0%) 14:19:41 INFO - TinderboxPrint: CPU user
1,626.9 (33.5%) 14:19:41 INFO - TinderboxPrint: Swap in / out
434,855,936 / 0 14:19:41 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400116224; Write bytes: 394913792; Read time: 29857; Write time: 784 14:19:42 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 29441024; Write bytes: 296586240; Read time: 184; Write time: 1119 14:19:42 INFO - Running post-run listener: _upload_blobber_files 14:19:42 INFO - Blob upload gear active. 14:19:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:19:42 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 14:19:42 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'] 14:19:42 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 14:19:42 INFO - (blobuploader) - INFO - Open directory for files ... 14:19:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:19:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:19:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:19:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:19:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:19:44 INFO - (blobuploader) - INFO - Done attempting. 14:19:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:19:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:19:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:19:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:19:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:19:45 INFO - (blobuploader) - INFO - Done attempting. 14:19:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:19:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:19:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:19:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:19:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:19:47 INFO - (blobuploader) - INFO - Done attempting. 14:19:47 INFO - (blobuploader) - INFO - Iteration through files over. 14:19:47 INFO - Return code: 0 14:19:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:19:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:19:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c692d6186840dbb2a83e00ee85ee830d6eaa07ebe2b1e10c944ab3a22aa04d8c39edc30e4ed3cf7f13a15165175bd9e762823dabfa15cde864ef73ef81cb128f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21b89fb16b3d4edf35956a06214f3f605995a507933fde65f3e08fa5b0af510b0ec67e97119a36bdbf93639c981246b69750fede506ad2c0bac0814a88b1c429", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97379d51c0922651906807c8c1ec416ac35a3428d8c2ac6e0c703ed5d0c63684a3c85e94bb7cdf9216fad8bc6a667ecc88892cae8bfc2620043f91cff261b99d"} 14:19:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:19:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:19:47 INFO - Contents: 14:19:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c692d6186840dbb2a83e00ee85ee830d6eaa07ebe2b1e10c944ab3a22aa04d8c39edc30e4ed3cf7f13a15165175bd9e762823dabfa15cde864ef73ef81cb128f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21b89fb16b3d4edf35956a06214f3f605995a507933fde65f3e08fa5b0af510b0ec67e97119a36bdbf93639c981246b69750fede506ad2c0bac0814a88b1c429", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97379d51c0922651906807c8c1ec416ac35a3428d8c2ac6e0c703ed5d0c63684a3c85e94bb7cdf9216fad8bc6a667ecc88892cae8bfc2620043f91cff261b99d"} 14:19:47 INFO - Running post-run listener: copy_logs_to_upload_dir 14:19:47 INFO - Copying logs to upload dir... 14:19:47 INFO - mkdir: /builds/slave/test/build/upload/logs 14:19:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.166947 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-09 14:19:47.641514) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-09 14:19:47.644739) ========= 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.9joIxGepQg/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.GRsJe4EG39/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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/c692d6186840dbb2a83e00ee85ee830d6eaa07ebe2b1e10c944ab3a22aa04d8c39edc30e4ed3cf7f13a15165175bd9e762823dabfa15cde864ef73ef81cb128f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21b89fb16b3d4edf35956a06214f3f605995a507933fde65f3e08fa5b0af510b0ec67e97119a36bdbf93639c981246b69750fede506ad2c0bac0814a88b1c429", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97379d51c0922651906807c8c1ec416ac35a3428d8c2ac6e0c703ed5d0c63684a3c85e94bb7cdf9216fad8bc6a667ecc88892cae8bfc2620043f91cff261b99d"} build_url:https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010984 build_url: 'https://queue.taskcluster.net/v1/task/M9pOvnTyRRS0-6QWyPwEvA/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/c692d6186840dbb2a83e00ee85ee830d6eaa07ebe2b1e10c944ab3a22aa04d8c39edc30e4ed3cf7f13a15165175bd9e762823dabfa15cde864ef73ef81cb128f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21b89fb16b3d4edf35956a06214f3f605995a507933fde65f3e08fa5b0af510b0ec67e97119a36bdbf93639c981246b69750fede506ad2c0bac0814a88b1c429", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97379d51c0922651906807c8c1ec416ac35a3428d8c2ac6e0c703ed5d0c63684a3c85e94bb7cdf9216fad8bc6a667ecc88892cae8bfc2620043f91cff261b99d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-09 14:19:47.682084) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 14:19:47.682662) ========= 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.9joIxGepQg/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.GRsJe4EG39/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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.004996 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 14:19:47.731599) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-09 14:19:47.731938) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-09 14:19:47.732307) ========= ========= Total master_lag: 0.30 =========