builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0105 starttime: 1477440942.74 results: success (0) buildid: 20161025145223 builduid: 24a6603ab69840ca8c29db1d23b97ad2 revision: 2ffe206d6af194997784bc7fa4abc3ac38dcc5cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:42.743976) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:42.744442) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:42.744729) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:42.888914) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:42.889223) ========= 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.e5exXDl5Gj/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.7A1rZR9QZC/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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-25 17:15:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.17M=0.001s 2016-10-25 17:15:43 (8.17 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.544160 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:43.456231) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:43.456600) ========= 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.e5exXDl5Gj/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.7A1rZR9QZC/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.020702 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:43.508475) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-25 17:15:43.508950) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 2ffe206d6af194997784bc7fa4abc3ac38dcc5cd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 2ffe206d6af194997784bc7fa4abc3ac38dcc5cd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.e5exXDl5Gj/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.7A1rZR9QZC/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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-25 17:15:43,582 truncating revision to first 12 chars 2016-10-25 17:15:43,582 Setting DEBUG logging. 2016-10-25 17:15:43,583 attempt 1/10 2016-10-25 17:15:43,583 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/2ffe206d6af1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-25 17:15:44,949 unpacking tar archive at: larch-2ffe206d6af1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.598201 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-25 17:15:45.133128) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:45.133634) ========= script_repo_revision: 2ffe206d6af194997784bc7fa4abc3ac38dcc5cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:45.134380) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:45.134653) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:45.155203) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-25 17:15:45.155504) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.e5exXDl5Gj/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.7A1rZR9QZC/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 17:15:45 INFO - MultiFileLogger online at 20161025 17:15:45 in /builds/slave/test 17:15:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 17:15:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:15:45 INFO - {'append_to_log': False, 17:15:45 INFO - 'base_work_dir': '/builds/slave/test', 17:15:45 INFO - 'blob_upload_branch': 'Larch', 17:15:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:15:45 INFO - 'branch': 'Larch', 17:15:45 INFO - 'buildbot_json_path': 'buildprops.json', 17:15:45 INFO - 'config_files': ('talos/mac_config.py',), 17:15:45 INFO - 'default_actions': ('clobber', 17:15:45 INFO - 'read-buildbot-config', 17:15:45 INFO - 'download-and-extract', 17:15:45 INFO - 'populate-webroot', 17:15:45 INFO - 'create-virtualenv', 17:15:45 INFO - 'install', 17:15:45 INFO - 'run-tests'), 17:15:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:15:45 INFO - 'download_minidump_stackwalk': True, 17:15:45 INFO - 'download_symbols': 'ondemand', 17:15:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:15:45 INFO - 'tooltool.py': '/tools/tooltool.py', 17:15:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:15:45 INFO - '/tools/misc-python/virtualenv.py')}, 17:15:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:15:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:15:45 INFO - 'installer_path': 'installer.exe', 17:15:45 INFO - 'log_level': 'info', 17:15:45 INFO - 'log_name': 'talos', 17:15:45 INFO - 'log_to_console': True, 17:15:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:15:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:15:45 INFO - 'opt_config_files': (), 17:15:45 INFO - 'pip_index': False, 17:15:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:15:45 INFO - 'cmd': ('bash', 17:15:45 INFO - '-c', 17:15:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:15:45 INFO - 'enabled': True, 17:15:45 INFO - 'halt_on_failure': False, 17:15:45 INFO - 'name': 'check_screen_resolution'},), 17:15:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:15:45 INFO - 'cmd': ('bash', 17:15:45 INFO - '-c', 17:15:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:15:45 INFO - 'enabled': True, 17:15:45 INFO - 'halt_on_failure': False, 17:15:45 INFO - 'name': 'check_screen_resolution'},), 17:15:45 INFO - 'run_cmd_checks_enabled': True, 17:15:45 INFO - 'sps_profile': False, 17:15:45 INFO - 'sps_profile_interval': 0, 17:15:45 INFO - 'suite': 'dromaeojs', 17:15:45 INFO - 'system_bits': '32', 17:15:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:15:45 INFO - 'title': 't-yosemite-r7-0105', 17:15:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:15:45 INFO - 'use_talos_json': True, 17:15:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:15:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:15:45 INFO - 'work_dir': 'build'} 17:15:45 INFO - [mozharness: 2016-10-26 00:15:45.358063Z] Running clobber step. 17:15:45 INFO - Running pre-action listener: _resource_record_pre_action 17:15:45 INFO - Running main action method: clobber 17:15:45 INFO - rmtree: /builds/slave/test/build 17:15:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:15:47 INFO - Running post-action listener: _resource_record_post_action 17:15:47 INFO - [mozharness: 2016-10-26 00:15:47.198584Z] Finished clobber step (success) 17:15:47 INFO - [mozharness: 2016-10-26 00:15:47.198769Z] Running read-buildbot-config step. 17:15:47 INFO - Running pre-action listener: _resource_record_pre_action 17:15:47 INFO - Running main action method: read_buildbot_config 17:15:47 INFO - Using buildbot properties: 17:15:47 INFO - { 17:15:47 INFO - "project": "", 17:15:47 INFO - "product": "firefox", 17:15:47 INFO - "script_repo_revision": "production", 17:15:47 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 17:15:47 INFO - "repository": "", 17:15:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 17:15:47 INFO - "buildid": "20161025145223", 17:15:47 INFO - "pgo_build": "False", 17:15:47 INFO - "basedir": "/builds/slave/test", 17:15:47 INFO - "buildnumber": 2, 17:15:47 INFO - "slavename": "t-yosemite-r7-0105", 17:15:47 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 17:15:47 INFO - "platform": "yosemite_r7", 17:15:47 INFO - "scheduler": "tests-larch-macosx64-talos", 17:15:47 INFO - "branch": "larch", 17:15:47 INFO - "repo_path": "projects/larch", 17:15:47 INFO - "revision": "2ffe206d6af194997784bc7fa4abc3ac38dcc5cd", 17:15:47 INFO - "stage_platform": "macosx64", 17:15:47 INFO - "builduid": "24a6603ab69840ca8c29db1d23b97ad2", 17:15:47 INFO - "slavebuilddir": "test" 17:15:47 INFO - } 17:15:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 17:15:47 INFO - Running post-action listener: _resource_record_post_action 17:15:47 INFO - [mozharness: 2016-10-26 00:15:47.202160Z] Finished read-buildbot-config step (success) 17:15:47 INFO - [mozharness: 2016-10-26 00:15:47.202266Z] Running download-and-extract step. 17:15:47 INFO - Running pre-action listener: _resource_record_pre_action 17:15:47 INFO - Running main action method: download_and_extract 17:15:47 INFO - mkdir: /builds/slave/test/build/tests 17:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:47 INFO - trying https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 17:15:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/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 17:15:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/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 17:15:48 INFO - Downloaded 1199 bytes. 17:15:48 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 17:15:48 INFO - Using the following test package requirements: 17:15:48 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:15:48 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 17:15:48 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 17:15:48 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'jsshell-mac.zip'], 17:15:48 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 17:15:48 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:15:48 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 17:15:48 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 17:15:48 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 17:15:48 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 17:15:48 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:15:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:15:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:15:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:15:48 INFO - Http code: 200 17:15:48 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 17:15:48 INFO - x-amz-cf-id: tkPacREZbWvHtOy2whRXNeCAWSem7ypl5BwO-5X02cgjk0gu5act3g== 17:15:48 INFO - x-amz-version-id: xj3y7kLOgYSGZZU0dd2G5xyB.2Lk1UXT 17:15:49 INFO - Expected file size: 16409300 17:15:49 INFO - Obtained file size: 16409300 17:15:55 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 17:15:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:15:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:15:55 INFO - Fetch https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:15:56 INFO - Http code: 200 17:15:56 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 17:15:56 INFO - x-amz-cf-id: xMzRnE3X9-DhMV6iXgYc8UzBoi4ZNkGLoPbyuey3Kz9oQPZqZsLs_A== 17:15:56 INFO - x-amz-version-id: xj3y7kLOgYSGZZU0dd2G5xyB.2Lk1UXT 17:15:56 INFO - Expected file size: 16409300 17:15:56 INFO - Obtained file size: 16409300 17:16:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 17:16:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:16:03 INFO - Fetch https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 17:16:04 INFO - Http code: 200 17:16:04 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 17:16:04 INFO - x-amz-cf-id: 1z5SbyG1ZoUVdAT-Xb0c-x7JG9zbvE9l7B1ODd-C3HeFoLzGo9rLjA== 17:16:04 INFO - x-amz-version-id: Q92fF9gqErhvvOHVVbpLuKTln2GygOZG 17:16:04 INFO - Expected file size: 14409028 17:16:04 INFO - Obtained file size: 14409028 17:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:04 INFO - trying https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:16:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 17:16:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/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 17:16:08 INFO - Downloaded 111295960 bytes. 17:16:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:16:08 INFO - mkdir: /builds/slave/test/properties 17:16:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:16:08 INFO - Writing to file /builds/slave/test/properties/build_url 17:16:08 INFO - Contents: 17:16:08 INFO - build_url:https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:16:08 INFO - Running post-action listener: _resource_record_post_action 17:16:08 INFO - Running post-action listener: set_extra_try_arguments 17:16:08 INFO - [mozharness: 2016-10-26 00:16:08.766539Z] Finished download-and-extract step (success) 17:16:08 INFO - [mozharness: 2016-10-26 00:16:08.766645Z] Running populate-webroot step. 17:16:08 INFO - Running pre-action listener: _resource_record_pre_action 17:16:08 INFO - Running main action method: populate_webroot 17:16:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:16:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:16:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:16:08 INFO - '--tppagecycles', 17:16:08 INFO - '7'], 17:16:08 INFO - 'tests': ['tsvgm']}}, 17:16:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:16:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:16:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:16:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:16:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['damp', 'tps']}, 17:16:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'tests': ['damp', 'tps']}, 17:16:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['dromaeo_dom']}, 17:16:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:16:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['basic_compositor_video']}, 17:16:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:16:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['a11yr', 17:16:08 INFO - 'ts_paint', 17:16:08 INFO - 'tpaint', 17:16:08 INFO - 'sessionrestore', 17:16:08 INFO - 'sessionrestore_no_auto_restore', 17:16:08 INFO - 'tabpaint']}, 17:16:08 INFO - 'other-e10s': {'tests': ['a11yr', 17:16:08 INFO - 'ts_paint', 17:16:08 INFO - 'tpaint', 17:16:08 INFO - 'sessionrestore', 17:16:08 INFO - 'sessionrestore_no_auto_restore', 17:16:08 INFO - 'tabpaint']}, 17:16:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:16:08 INFO - 'ts_paint', 17:16:08 INFO - 'tpaint', 17:16:08 INFO - 'sessionrestore', 17:16:08 INFO - 'sessionrestore_no_auto_restore', 17:16:08 INFO - 'tabpaint']}, 17:16:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:16:08 INFO - 'ts_paint', 17:16:08 INFO - 'tpaint', 17:16:08 INFO - 'sessionrestore', 17:16:08 INFO - 'sessionrestore_no_auto_restore', 17:16:08 INFO - 'tabpaint']}, 17:16:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['a11yr', 17:16:08 INFO - 'ts_paint', 17:16:08 INFO - 'tpaint', 17:16:08 INFO - 'sessionrestore', 17:16:08 INFO - 'sessionrestore_no_auto_restore', 17:16:08 INFO - 'tabpaint']}, 17:16:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['a11yr', 17:16:08 INFO - 'ts_paint', 17:16:08 INFO - 'tpaint', 17:16:08 INFO - 'sessionrestore', 17:16:08 INFO - 'sessionrestore_no_auto_restore', 17:16:08 INFO - 'tabpaint']}, 17:16:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['tsvgx', 17:16:08 INFO - 'tsvgr_opacity', 17:16:08 INFO - 'tart', 17:16:08 INFO - 'tscrollx', 17:16:08 INFO - 'cart']}, 17:16:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:16:08 INFO - 'tsvgr_opacity', 17:16:08 INFO - 'tart', 17:16:08 INFO - 'tscrollx', 17:16:08 INFO - 'cart']}, 17:16:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['tp5o']}, 17:16:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'tests': ['tp5o']}, 17:16:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'talos_options': ['--disable-e10s', 17:16:08 INFO - '--xperf_path', 17:16:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:08 INFO - 'tests': ['tp5n']}, 17:16:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'talos_options': ['--xperf_path', 17:16:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:08 INFO - 'tests': ['tp5n']}}, 17:16:08 INFO - 'talos.zip': {'path': '', 17:16:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:16:08 INFO - Running post-action listener: _resource_record_post_action 17:16:08 INFO - [mozharness: 2016-10-26 00:16:08.785080Z] Finished populate-webroot step (success) 17:16:08 INFO - [mozharness: 2016-10-26 00:16:08.785214Z] Running create-virtualenv step. 17:16:08 INFO - Running pre-action listener: _resource_record_pre_action 17:16:08 INFO - Running main action method: create_virtualenv 17:16:08 INFO - Creating virtualenv /builds/slave/test/build/venv 17:16:08 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 17:16:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:16:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:16:08 INFO - Using real prefix '/tools/python27' 17:16:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:16:09 INFO - Installing distribute.............................................................................................................................................................................................done. 17:16:12 INFO - Installing pip.................done. 17:16:12 INFO - Return code: 0 17:16:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:16:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:16:12 INFO - Reading from file tmpfile_stdout 17:16:12 INFO - Output received: 17:16:12 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) 17:16:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:16: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')]} 17:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16: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')]} 17:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a31c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc369569000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a130d30>, '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 0x109f56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a128b20>, '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.e5exXDl5Gj/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.7A1rZR9QZC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 17:16: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', 'pip>=1.5'] in /builds/slave/test/build 17:16: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 pip>=1.5 17:16:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e5exXDl5Gj/Render', 17:16:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:12 INFO - 'HOME': '/Users/cltbld', 17:16:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:12 INFO - 'LOGNAME': 'cltbld', 17:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:12 INFO - 'MOZ_NO_REMOTE': '1', 17:16:12 INFO - 'NO_EM_RESTART': '1', 17:16:12 INFO - 'PAGER': '/bin/cat', 17:16:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:12 INFO - 'PWD': '/builds/slave/test', 17:16:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:12 INFO - 'SHELL': '/bin/bash', 17:16:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7A1rZR9QZC/Listeners', 17:16:12 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 17:16:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:12 INFO - 'USER': 'cltbld', 17:16:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:12 INFO - 'XPC_FLAGS': '0x0', 17:16:12 INFO - 'XPC_SERVICE_NAME': '0', 17:16:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:13 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 17:16:13 INFO - Cleaning up... 17:16:13 INFO - Return code: 0 17:16:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:13 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 0x10a31c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc369569000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a130d30>, '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 0x109f56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a128b20>, '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.e5exXDl5Gj/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.7A1rZR9QZC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 17:16:13 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 17:16:13 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 17:16:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e5exXDl5Gj/Render', 17:16:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:13 INFO - 'HOME': '/Users/cltbld', 17:16:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:13 INFO - 'LOGNAME': 'cltbld', 17:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:13 INFO - 'MOZ_NO_REMOTE': '1', 17:16:13 INFO - 'NO_EM_RESTART': '1', 17:16:13 INFO - 'PAGER': '/bin/cat', 17:16:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:13 INFO - 'PWD': '/builds/slave/test', 17:16:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:13 INFO - 'SHELL': '/bin/bash', 17:16:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7A1rZR9QZC/Listeners', 17:16:13 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 17:16:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:13 INFO - 'USER': 'cltbld', 17:16:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:13 INFO - 'XPC_FLAGS': '0x0', 17:16:13 INFO - 'XPC_SERVICE_NAME': '0', 17:16:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:13 INFO - Downloading/unpacking psutil>=3.1.1 17:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:16:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:16:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:16:17 INFO - Installing collected packages: psutil 17:16:17 INFO - Running setup.py install for psutil 17:16:17 INFO - building 'psutil._psutil_osx' extension 17:16:17 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 17:16:17 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 17:16:17 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 17:16:17 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 17:16:17 INFO - building 'psutil._psutil_posix' extension 17:16:17 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 17:16:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:16:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:16:17 INFO - ^ 17:16:17 INFO - 1 warning generated. 17:16:17 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 17:16:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:16:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:16:18 INFO - Successfully installed psutil 17:16:18 INFO - Cleaning up... 17:16:18 INFO - Return code: 0 17:16:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:18 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 0x10a31c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc369569000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a130d30>, '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 0x109f56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a128b20>, '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.e5exXDl5Gj/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.7A1rZR9QZC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 17:16:18 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 17:16:18 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 17:16:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e5exXDl5Gj/Render', 17:16:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:18 INFO - 'HOME': '/Users/cltbld', 17:16:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:18 INFO - 'LOGNAME': 'cltbld', 17:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:18 INFO - 'MOZ_NO_REMOTE': '1', 17:16:18 INFO - 'NO_EM_RESTART': '1', 17:16:18 INFO - 'PAGER': '/bin/cat', 17:16:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:18 INFO - 'PWD': '/builds/slave/test', 17:16:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:18 INFO - 'SHELL': '/bin/bash', 17:16:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7A1rZR9QZC/Listeners', 17:16:18 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 17:16:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:18 INFO - 'USER': 'cltbld', 17:16:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:18 INFO - 'XPC_FLAGS': '0x0', 17:16:18 INFO - 'XPC_SERVICE_NAME': '0', 17:16:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:16:18 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 17:16:18 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 17:16:18 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 17:16:18 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 17:16:18 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 17:16:18 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 17:16:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:16:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:16:20 INFO - Installing collected packages: mozsystemmonitor 17:16:20 INFO - Running setup.py install for mozsystemmonitor 17:16:21 INFO - Successfully installed mozsystemmonitor 17:16:21 INFO - Cleaning up... 17:16:21 INFO - Return code: 0 17:16:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:16:21 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')]} 17:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:21 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')]} 17:16:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:21 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 0x10a31c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc369569000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a130d30>, '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 0x109f56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a128b20>, '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.e5exXDl5Gj/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.7A1rZR9QZC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 17:16:21 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 17:16:21 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 17:16:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e5exXDl5Gj/Render', 17:16:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:21 INFO - 'HOME': '/Users/cltbld', 17:16:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:21 INFO - 'LOGNAME': 'cltbld', 17:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:21 INFO - 'MOZ_NO_REMOTE': '1', 17:16:21 INFO - 'NO_EM_RESTART': '1', 17:16:21 INFO - 'PAGER': '/bin/cat', 17:16:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:21 INFO - 'PWD': '/builds/slave/test', 17:16:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:21 INFO - 'SHELL': '/bin/bash', 17:16:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7A1rZR9QZC/Listeners', 17:16:21 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 17:16:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:21 INFO - 'USER': 'cltbld', 17:16:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:21 INFO - 'XPC_FLAGS': '0x0', 17:16:21 INFO - 'XPC_SERVICE_NAME': '0', 17:16:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:21 INFO - Downloading/unpacking jsonschema==2.5.1 17:16:21 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 17:16:21 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 17:16:21 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 17:16:21 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 17:16:21 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 17:16:21 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 17:16:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:16:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:24 INFO - Downloading functools32-3.2.3-2.tar.gz 17:16:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:16:24 INFO - warning: no files found matching '*.txt' 17:16:24 INFO - no previously-included directories found matching 'build' 17:16:24 INFO - no previously-included directories found matching 'dist' 17:16:24 INFO - no previously-included directories found matching '.git*' 17:16:24 INFO - Installing collected packages: jsonschema, functools32 17:16:24 INFO - Running setup.py install for functools32 17:16:24 INFO - warning: no files found matching '*.txt' 17:16:24 INFO - no previously-included directories found matching 'build' 17:16:24 INFO - no previously-included directories found matching 'dist' 17:16:24 INFO - no previously-included directories found matching '.git*' 17:16:24 INFO - Successfully installed jsonschema functools32 17:16:24 INFO - Cleaning up... 17:16:24 INFO - Return code: 0 17:16:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:16:24 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')]} 17:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:24 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')]} 17:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:24 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 0x10a31c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc369569000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a130d30>, '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 0x109f56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a128b20>, '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.e5exXDl5Gj/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.7A1rZR9QZC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 17:16:24 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 17:16:24 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 17:16:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e5exXDl5Gj/Render', 17:16:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:24 INFO - 'HOME': '/Users/cltbld', 17:16:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:24 INFO - 'LOGNAME': 'cltbld', 17:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:24 INFO - 'MOZ_NO_REMOTE': '1', 17:16:24 INFO - 'NO_EM_RESTART': '1', 17:16:24 INFO - 'PAGER': '/bin/cat', 17:16:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:24 INFO - 'PWD': '/builds/slave/test', 17:16:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:24 INFO - 'SHELL': '/bin/bash', 17:16:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7A1rZR9QZC/Listeners', 17:16:24 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 17:16:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:24 INFO - 'USER': 'cltbld', 17:16:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:24 INFO - 'XPC_FLAGS': '0x0', 17:16:24 INFO - 'XPC_SERVICE_NAME': '0', 17:16:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:16:25 INFO - Cleaning up... 17:16:25 INFO - Return code: 0 17:16:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:16:25 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')]} 17:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:25 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')]} 17:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:25 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 0x10a31c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc369569000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a130d30>, '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 0x109f56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a128b20>, '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.e5exXDl5Gj/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.7A1rZR9QZC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 17:16:25 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 17:16:25 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 17:16:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e5exXDl5Gj/Render', 17:16:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:25 INFO - 'HOME': '/Users/cltbld', 17:16:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:25 INFO - 'LOGNAME': 'cltbld', 17:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:25 INFO - 'MOZ_NO_REMOTE': '1', 17:16:25 INFO - 'NO_EM_RESTART': '1', 17:16:25 INFO - 'PAGER': '/bin/cat', 17:16:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:25 INFO - 'PWD': '/builds/slave/test', 17:16:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:25 INFO - 'SHELL': '/bin/bash', 17:16:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7A1rZR9QZC/Listeners', 17:16:25 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 17:16:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:25 INFO - 'USER': 'cltbld', 17:16:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:25 INFO - 'XPC_FLAGS': '0x0', 17:16:25 INFO - 'XPC_SERVICE_NAME': '0', 17:16:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:25 INFO - Downloading/unpacking blobuploader==1.2.4 17:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:27 INFO - Downloading blobuploader-1.2.4.tar.gz 17:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:16:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:16:27 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 17:16:27 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 17:16:27 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 17:16:27 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 17:16:27 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 17:16:27 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 17:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:16:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:16:28 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 17:16:28 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 17:16:28 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 17:16:28 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 17:16:28 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 17:16:28 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 17:16:28 INFO - Downloading docopt-0.6.1.tar.gz 17:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:16:29 INFO - Installing collected packages: blobuploader, requests, docopt 17:16:29 INFO - Running setup.py install for blobuploader 17:16:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:16:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:16:29 INFO - Running setup.py install for requests 17:16:29 INFO - Running setup.py install for docopt 17:16:29 INFO - Successfully installed blobuploader requests docopt 17:16:29 INFO - Cleaning up... 17:16:29 INFO - Return code: 0 17:16:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:29 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 0x10a31c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc369569000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a130d30>, '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 0x109f56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a128b20>, '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.e5exXDl5Gj/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.7A1rZR9QZC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 17:16:29 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 17:16:29 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 17:16:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e5exXDl5Gj/Render', 17:16:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:29 INFO - 'HOME': '/Users/cltbld', 17:16:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:29 INFO - 'LOGNAME': 'cltbld', 17:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:29 INFO - 'MOZ_NO_REMOTE': '1', 17:16:29 INFO - 'NO_EM_RESTART': '1', 17:16:29 INFO - 'PAGER': '/bin/cat', 17:16:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:29 INFO - 'PWD': '/builds/slave/test', 17:16:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:29 INFO - 'SHELL': '/bin/bash', 17:16:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7A1rZR9QZC/Listeners', 17:16:29 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 17:16:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:29 INFO - 'USER': 'cltbld', 17:16:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:29 INFO - 'XPC_FLAGS': '0x0', 17:16:29 INFO - 'XPC_SERVICE_NAME': '0', 17:16:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:16:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-t_UR7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:16:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Gt2KNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:16:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-iv1q6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:16:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-cLVhLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:16:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-5BwWtR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:16:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-0zmIAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:16:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-u4Pfht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:16:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-RXdwgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:16:31 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-da5S7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:16:31 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-vmjglR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:16:31 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ff6YwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:16:31 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-4oR0W5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:16:31 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-hceYrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:16:31 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-AJYXBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:31 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ER2cj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:16:31 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-AbLFvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:16:31 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-_2n41U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:16:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:16:32 INFO - Running setup.py install for manifestparser 17:16:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:16:32 INFO - Running setup.py install for mozcrash 17:16:32 INFO - Running setup.py install for mozdebug 17:16:32 INFO - Running setup.py install for mozdevice 17:16:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:16:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:16:32 INFO - Running setup.py install for mozfile 17:16:32 INFO - Running setup.py install for mozhttpd 17:16:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:16:32 INFO - Running setup.py install for mozinfo 17:16:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:16:33 INFO - Running setup.py install for mozInstall 17:16:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:16:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:16:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:16:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:16:33 INFO - Running setup.py install for mozleak 17:16:33 INFO - Running setup.py install for mozlog 17:16:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:16:33 INFO - Running setup.py install for moznetwork 17:16:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:16:33 INFO - Running setup.py install for mozprocess 17:16:33 INFO - Running setup.py install for mozprofile 17:16:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:16:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:16:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:16:34 INFO - Running setup.py install for mozrunner 17:16:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:16:34 INFO - Running setup.py install for mozscreenshot 17:16:34 INFO - Running setup.py install for moztest 17:16:34 INFO - Running setup.py install for mozversion 17:16:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:16:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:16:34 INFO - Cleaning up... 17:16:34 INFO - Return code: 0 17:16:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16: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')]} 17:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16: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')]} 17:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:34 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 0x10a31c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc369569000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a130d30>, '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 0x109f56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a128b20>, '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.e5exXDl5Gj/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.7A1rZR9QZC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 17:16:34 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 17:16:34 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 17:16:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e5exXDl5Gj/Render', 17:16:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:34 INFO - 'HOME': '/Users/cltbld', 17:16:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:34 INFO - 'LOGNAME': 'cltbld', 17:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:34 INFO - 'MOZ_NO_REMOTE': '1', 17:16:34 INFO - 'NO_EM_RESTART': '1', 17:16:34 INFO - 'PAGER': '/bin/cat', 17:16:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:34 INFO - 'PWD': '/builds/slave/test', 17:16:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:34 INFO - 'SHELL': '/bin/bash', 17:16:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7A1rZR9QZC/Listeners', 17:16:34 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 17:16:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:34 INFO - 'USER': 'cltbld', 17:16:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:34 INFO - 'XPC_FLAGS': '0x0', 17:16:34 INFO - 'XPC_SERVICE_NAME': '0', 17:16:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:16:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-6671hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:16:35 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)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:16:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-AYOsmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:16:35 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)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:16:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-97m6A9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:16:35 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)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:16:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-BNYUV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:16:35 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)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:16:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-hC5O9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:16:35 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)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:16:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ZqR2IN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:16:35 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)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:16:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-P53JKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:16:35 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)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:16:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-SFunYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:16:35 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)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:16:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-FivNvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:16:36 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)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:16:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ZIxw6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:16:36 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)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:16:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-EbMvUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:16:36 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)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:16:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-jQtWAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:16:36 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)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:16:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-l9HYDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:16:36 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)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:16:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-uvb_VX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:16:36 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)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-zpUQA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:36 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)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:16:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-HPzBm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:16:36 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)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:16:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Kn1PcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:16:36 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)) 17:16:36 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)) 17:16:36 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)) 17:16:36 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)) 17:16:36 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)) 17:16:36 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)) 17:16:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:16:36 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 17:16:36 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 17:16:36 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 17:16:36 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 17:16:36 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 17:16:36 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 17:16:39 INFO - Downloading blessings-1.6.tar.gz 17:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:16:39 INFO - Installing collected packages: blessings 17:16:39 INFO - Running setup.py install for blessings 17:16:39 INFO - Successfully installed blessings 17:16:39 INFO - Cleaning up... 17:16:39 INFO - Return code: 0 17:16:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:16:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:16:40 INFO - Reading from file tmpfile_stdout 17:16:40 INFO - Current package versions: 17:16:40 INFO - blessings == 1.6 17:16:40 INFO - blobuploader == 1.2.4 17:16:40 INFO - docopt == 0.6.1 17:16:40 INFO - functools32 == 3.2.3-2 17:16:40 INFO - jsonschema == 2.5.1 17:16:40 INFO - manifestparser == 1.1 17:16:40 INFO - mozInstall == 1.12 17:16:40 INFO - mozcrash == 0.17 17:16:40 INFO - mozdebug == 0.1 17:16:40 INFO - mozdevice == 0.48 17:16:40 INFO - mozfile == 1.2 17:16:40 INFO - mozhttpd == 0.7 17:16:40 INFO - mozinfo == 0.9 17:16:40 INFO - mozleak == 0.1 17:16:40 INFO - mozlog == 3.4 17:16:40 INFO - moznetwork == 0.27 17:16:40 INFO - mozprocess == 0.23 17:16:40 INFO - mozprofile == 0.28 17:16:40 INFO - mozrunner == 6.12 17:16:40 INFO - mozscreenshot == 0.1 17:16:40 INFO - mozsystemmonitor == 0.3 17:16:40 INFO - moztest == 0.7 17:16:40 INFO - mozversion == 1.4 17:16:40 INFO - psutil == 3.1.1 17:16:40 INFO - requests == 1.2.3 17:16:40 INFO - wsgiref == 0.1.2 17:16:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:40 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')]} 17:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:40 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')]} 17:16:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:40 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 0x10a31c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc369569000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a130d30>, '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 0x109f56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a128b20>, '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.e5exXDl5Gj/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.7A1rZR9QZC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 17:16:40 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 17:16:40 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 17:16:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e5exXDl5Gj/Render', 17:16:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:40 INFO - 'HOME': '/Users/cltbld', 17:16:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:40 INFO - 'LOGNAME': 'cltbld', 17:16:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:40 INFO - 'MOZ_NO_REMOTE': '1', 17:16:40 INFO - 'NO_EM_RESTART': '1', 17:16:40 INFO - 'PAGER': '/bin/cat', 17:16:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:40 INFO - 'PWD': '/builds/slave/test', 17:16:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:40 INFO - 'SHELL': '/bin/bash', 17:16:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7A1rZR9QZC/Listeners', 17:16:40 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 17:16:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:40 INFO - 'USER': 'cltbld', 17:16:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:40 INFO - 'XPC_FLAGS': '0x0', 17:16:40 INFO - 'XPC_SERVICE_NAME': '0', 17:16:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 INFO - Cleaning up... 17:16:40 INFO - Return code: 0 17:16:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:16:40 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')]} 17:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:40 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')]} 17:16:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:40 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 0x10a31c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc369569000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a130d30>, '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 0x109f56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a128b20>, '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.e5exXDl5Gj/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.7A1rZR9QZC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 17:16:40 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 17:16:40 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 17:16:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e5exXDl5Gj/Render', 17:16:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:40 INFO - 'HOME': '/Users/cltbld', 17:16:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:40 INFO - 'LOGNAME': 'cltbld', 17:16:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:40 INFO - 'MOZ_NO_REMOTE': '1', 17:16:40 INFO - 'NO_EM_RESTART': '1', 17:16:40 INFO - 'PAGER': '/bin/cat', 17:16:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:40 INFO - 'PWD': '/builds/slave/test', 17:16:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:40 INFO - 'SHELL': '/bin/bash', 17:16:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7A1rZR9QZC/Listeners', 17:16:40 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 17:16:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:40 INFO - 'USER': 'cltbld', 17:16:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:40 INFO - 'XPC_FLAGS': '0x0', 17:16:40 INFO - 'XPC_SERVICE_NAME': '0', 17:16:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:16:40 INFO - Cleaning up... 17:16:40 INFO - Return code: 0 17:16:40 INFO - Running post-action listener: _resource_record_post_action 17:16:40 INFO - Running post-action listener: _start_resource_monitoring 17:16:40 INFO - Starting resource monitoring. 17:16:40 INFO - [mozharness: 2016-10-26 00:16:40.661033Z] Finished create-virtualenv step (success) 17:16:40 INFO - [mozharness: 2016-10-26 00:16:40.662147Z] Running install step. 17:16:40 INFO - Running pre-action listener: _resource_record_pre_action 17:16:40 INFO - Running main action method: install 17:16:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:16:40 INFO - Reading from file tmpfile_stdout 17:16:40 INFO - Detecting whether we're running mozinstall >=1.0... 17:16:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:16:41 INFO - Reading from file tmpfile_stdout 17:16:41 INFO - Output received: 17:16:41 INFO - Usage: mozinstall [options] installer 17:16:41 INFO - Options: 17:16:41 INFO - -h, --help show this help message and exit 17:16:41 INFO - -d DEST, --destination=DEST 17:16:41 INFO - Directory to install application into. [default: 17:16:41 INFO - "/builds/slave/test"] 17:16:41 INFO - --app=APP Application being installed. [default: firefox] 17:16:41 INFO - mkdir: /builds/slave/test/build/application 17:16:41 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'] 17:16:41 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 17:17:15 INFO - Reading from file tmpfile_stdout 17:17:15 INFO - Output received: 17:17:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:17:15 INFO - Running post-action listener: _resource_record_post_action 17:17:15 INFO - [mozharness: 2016-10-26 00:17:15.640589Z] Finished install step (success) 17:17:15 INFO - [mozharness: 2016-10-26 00:17:15.640768Z] Running run-tests step. 17:17:15 INFO - Running pre-action listener: _resource_record_pre_action 17:17:15 INFO - Running main action method: run_tests 17:17:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:17:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:17:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:17:15 INFO - 2016-10-25 17:17:15.655 screenresolution[2770:42141] starting screenresolution argv=screenresolution get 17:17:15 INFO - 2016-10-25 17:17:15.657 screenresolution[2770:42141] Display 0: 1600x1200x32@60 17:17:15 INFO - 2016-10-25 17:17:15.663 screenresolution[2771:42143] starting screenresolution argv=screenresolution list 17:17:15 INFO - Available Modes on Display 0 17:17:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:17:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:17:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:17:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:17:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:17:15 INFO - Intel Iris: 17:17:15 INFO - Chipset Model: Intel Iris 17:17:15 INFO - Type: GPU 17:17:15 INFO - Bus: Built-In 17:17:15 INFO - VRAM (Dynamic, Max): 1536 MB 17:17:15 INFO - Vendor: Intel (0x8086) 17:17:15 INFO - Device ID: 0x0a2e 17:17:15 INFO - Revision ID: 0x0009 17:17:15 INFO - Displays: 17:17:15 INFO - Display: 17:17:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:17:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:17:15 INFO - Display Serial Number: 1600x1200 60 17:17:15 INFO - Main Display: Yes 17:17:15 INFO - Mirror: Off 17:17:15 INFO - Online: Yes 17:17:15 INFO - Rotation: Supported 17:17:15 INFO - Return code: 0 17:17:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:17:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:17:15 INFO - Python 2.7.3 17:17:15 INFO - Return code: 0 17:17:15 INFO - grabbing minidump binary from tooltool 17:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:15 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a130d30>, '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 0x109f56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a128b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:17:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:17:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:17:15 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:17:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:17:16 INFO - Return code: 0 17:17:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:17:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:17:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:17:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:17:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 17:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0105 --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 17:17:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e5exXDl5Gj/Render', 17:17:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:16 INFO - 'HOME': '/Users/cltbld', 17:17:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:16 INFO - 'LOGNAME': 'cltbld', 17:17:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:17:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:16 INFO - 'MOZ_NO_REMOTE': '1', 17:17:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:17:16 INFO - 'NO_EM_RESTART': '1', 17:17:16 INFO - 'PAGER': '/bin/cat', 17:17:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:16 INFO - 'PWD': '/builds/slave/test', 17:17:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:17:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:16 INFO - 'SHELL': '/bin/bash', 17:17:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7A1rZR9QZC/Listeners', 17:17:16 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 17:17:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:16 INFO - 'USER': 'cltbld', 17:17:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:16 INFO - 'XPC_FLAGS': '0x0', 17:17:16 INFO - 'XPC_SERVICE_NAME': '0', 17:17:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 17:17:16 INFO - mozversion application_buildid: 20161025145223 17:17:16 INFO - mozversion application_changeset: 2ffe206d6af194997784bc7fa4abc3ac38dcc5cd 17:17:16 INFO - mozversion application_display_name: Nightly 17:17:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:17:16 INFO - mozversion application_name: Firefox 17:17:16 INFO - mozversion application_remotingname: firefox 17:17:16 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 17:17:16 INFO - mozversion application_vendor: Mozilla 17:17:16 INFO - mozversion application_version: 52.0a1 17:17:16 INFO - mozversion platform_buildid: 20161025145223 17:17:16 INFO - mozversion platform_changeset: 2ffe206d6af194997784bc7fa4abc3ac38dcc5cd 17:17:16 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 17:17:16 INFO - mozversion platform_version: 52.0a1 17:17:16 INFO - using testdate: 1477441036 17:17:16 INFO - actual date: 1477441036 17:17:16 INFO - starting webserver on 'localhost:50285' 17:17:16 INFO - SUITE-START | Running 2 tests 17:17:16 INFO - TEST-START | dromaeo_css 17:17:16 INFO - operating with platform_type : mac_ 17:17:16 INFO - Initialising browser for dromaeo_css test... 17:17:16 INFO - TEST-INFO | started process 2781 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpMg3vO3/profile http://localhost:50285/getInfo.html) 17:17:18 INFO - PROCESS | 2781 | __metrics Screen width/height:1600/1200 17:17:18 INFO - PROCESS | 2781 | colorDepth:24 17:17:18 INFO - PROCESS | 2781 | Browser inner width/height: 1024/689 17:17:18 INFO - PROCESS | 2781 | __metrics 17:17:18 INFO - PROCESS | 2781 | 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" 17:17:18 INFO - PROCESS | 2781 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:17:19 INFO - TEST-INFO | 2781: exit 0 17:17:19 INFO - Browser initialized. 17:17:19 INFO - Running cycle 1/1 for dromaeo_css test... 17:17:19 INFO - TEST-INFO | started process 2783 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpMg3vO3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:17:31 INFO - PROCESS | 2783 | RSS: Main: 232906752 17:17:31 INFO - PROCESS | 2783 | 17:18:23 INFO - PROCESS | 2783 | 1477441103267 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] 17:20:36 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:50285/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50285/tests/dromaeo/cssquery-ext.html) 17:20:38 INFO - PROCESS | 2783 | RSS: Main: 396398592 17:20:38 INFO - PROCESS | 2783 | 17:23:41 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:50285/tests/dromaeo/cssquery-ext.html (next: http://localhost:50285/tests/dromaeo/cssquery-jquery.html) 17:23:42 INFO - PROCESS | 2783 | RSS: Main: 370442240 17:23:42 INFO - PROCESS | 2783 | 17:26:44 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:50285/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50285/tests/dromaeo/cssquery-mootools.html) 17:26:45 INFO - PROCESS | 2783 | RSS: Main: 342257664 17:26:45 INFO - PROCESS | 2783 | 17:29:47 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:50285/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50285/tests/dromaeo/cssquery-prototype.html) 17:29:48 INFO - PROCESS | 2783 | RSS: Main: 337170432 17:29:48 INFO - PROCESS | 2783 | 17:32:51 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:50285/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50285/tests/dromaeo/cssquery-yui.html) 17:32:52 INFO - PROCESS | 2783 | RSS: Main: 332201984 17:32:52 INFO - PROCESS | 2783 | 17:35:55 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:50285/tests/dromaeo/cssquery-yui.html (next: http://localhost:50285/tests/dromaeo/cssquery-dojo.html) 17:35:55 INFO - PROCESS | 2783 | RSS: Main: 393711616 17:35:55 INFO - PROCESS | 2783 | 17:35:55 INFO - PROCESS | 2783 | __start_tp_report 17:35:55 INFO - PROCESS | 2783 | _x_x_mozilla_page_load 17:35:55 INFO - PROCESS | 2783 | _x_x_mozilla_page_load_details 17:35:55 INFO - PROCESS | 2783 | |i|pagename|runs| 17:35:55 INFO - PROCESS | 2783 | |0;dojo.html;3271.9182020449502;3279.5408642790007;3280.606327240731;3321.7342612590996;3326.2349659578294;5540.584456165793;5632.577556683244;5662.801801936933;5698.031334673105;5711.885762284752;5678.744456499458;5689.886202275962;5789.2763404574425;5805.24531810864;5818.7963421280265;5079.3650793650795;5131;5139.0749665060275;5212.609054320922;5216.295800067;5314.867128321785;5331.360236771593;5337.172738225562;5339.813106541272;5352.036633405985;5128.25640282157;5288.550473209782;5327.866803329928;5354.196870469422;5391.622586418952;5199.402068762103;5237.083510385705;5243.056249875021;5253.553447956928;5256.395514515803;5162.380514338266;5199.922001169985;5200.947990520084;5219.530242278213;5228.189630607262;5697.287839020122;5706.343770466408;5708.257926469532;5711.20043193949;5725.885482290372;6153.876922461571;6171.7531298748445;6189.783357582463;6194.907076393858;6204.9689751551405;6166.260048794128;6169.629822210637;6187.659678717669;6235.158253635746;6238.4697300729495;4927.236278376858;5826.359100498942;5827.172271245729;5838.5329173666005;5878.911816322801;6138.457541419318;6202.627842329474;6239.812805615838;6252.624842509464;6257.342978987232;6098.390160983866;6168.9691551541955;6179.62922224668;6183.628982261079;6187.443130118311;6238.781642642486;6238.875222495525;6248.562600618004;6256.906146407808;6270.028145637433;6074.787382441682;6101.4290607115445;6102.479535881749;6106.36966090081;6224.751009959551;6146.416090471398;6206.813795586139;6207.006878899354;6211.2857021443115;6214.067889816563;4878.756062196876;4905.7301848397965;4912.9508704913205;4922.90154196914;4949.554540091337;3912.3935789952034;4417.359482875029;4447.355133505624;4448.354988526646;4465.776711164429;5626.2967129108865;5691.060974939089;5692.857678557986;5700.515456186187;5722.341930678025;4785.736784476818;6145.631262124287;6147;6152.323244443108;6156.8768624627255;4882.975585122052;5702.572307076985;5774.364819869811;5842;5848.561357898175;5355.973220133875;5400;5403.83788486346;5404.594655400861;5425.104857698436;6190.50475961931;6192.195014648157;6192.473639740672;6199.535034872402;6212.751489940351;6109.7387008107025;6111.083337499411;6132.78535251264;6139.938600614116;6144.631322120687;5294.549963253013;6096.0429282035075;6098.613770999223;6099.24925446448;6102.974033066949;5647.887042259214;5861.827634473208;6108.797423689751;6134.35589263121;6163.137160797582;6144;6144.938550614436;6153.046277826944;6156.846078847885;6166.2600487939935;5990.850228744316;6079.300880398634;6117.755289788537;6124.724387402489;6128.448439640454;6044.90932636002;6077.11881777131;6079.635221886701;6117.462039585284;6136.6011209271255;5980.5514586405325;6003.949308870843;6026.578139530191;6031.24609423822;6051.3041000283765;6104.755809767737;6116.449519543263;6129.1112788644505;6129.969350153399;6133.233345831771;5800.9419905800405;5883.352831188651;5885.73514191871;5922.052471604523;5954.612950158399 17:35:55 INFO - PROCESS | 2783 | |1;ext.html;456.2221412491702;505.58289411235734;511.74668539073167;517.9300794392758;544.8964696707628;30801.22996925078;31322.53016204756;31336.84331578339;31341.84329078354;40069.799651001704;40556.18887622253;42727.572724272744;42729.23155374024;42758.786206069;42776.57223427765;9580.664676736316;9706.514674266295;9726.513674316291;9744.31789774716;9745.853812192823;8635.352348573851;8828.73513794584;9464.716058518221;9602.951985240099;11522;11485;11656.7668646627;11694.532218711283;11694.649160525198;11753.177277590572;11577.131715121399;11690.590829320932;11699.122565807598;11707.473163707653;11725.530978760838;8523.445976011539;8710;11367.545298188063;11468;11560.6531804046;40388.19223615541;40507.18985620271;40667.59332406668;40698.38952415687;40712.185756285005;3351.597808263024;3383.2218589724257;3440.9483857742152;3497.632748561415;3694.242680250542;25332.620010699855;28542.00102996406;29591.852040739876;29766.70233297661;29925.551116733266;3003.6996300370006;3030.7423868971164;3112.1286039908637;3238.141892398516;3255.9837200813845;474592.62703686603;477103;479778;479922;480813;58162.709186453794;58311.70844145752;58399;58497.41502584919;58623.82752344929;54260.45739542554;60440;60456.79086418247;61476;61589.9933008706;15201.923990380199;15775.921120394325;15834.76247856283;15848.96981696186;15866.968647038137;12193.634190974284;12330.19853709506;12330.81503777444;12352.44414001372;12376;13874.098183618033;14190.787138192936;14194.503192388216;15297.847021529642;15919.099820544041;5302.655327403706;5930.584859059824;5937.2578427696535;5939.524838012949;5939.7229595637145;903.9378729992233;929.0384452092052;960.1708744841089;967.7387105481621;1003.5734812704571;21840.672389914165;29452.705472945425;29496.262593435335;30197.849010755246;30319;1006.3911333643041;1022.4427686910503;1066.1630619963291;1089.5968491658136;1110.9888901111046;13243.337833108306;14745.557633271019;15054.924725376522;15778.447754328772;16220;548954;622458;622799;623159;623222;50527.49472505302;51306.74346628243;57062.714686427134;57675.423245767844;58157;1318.2881244128255;1367.5076972289967;1367.8358596968228;1368.4526189524072;1368.6920442900268;29887.252818679706;30019.249518762204;30244.395112098075;30294.091177264716;30471;45759;46153.07693846037;46224.537754622026;46253;46304.53695463002;613799;619704;631996;767404;770907;24470.755292446847;24733.38166545779;24784.752152478966;25108.874455627603;25154.49691006133;45117.32324015077;45385.773071134434;45816;45845.31232031589;45893;38597.45610175674;38756.41865372078;39100.413493797045;39595;40042.79978600088;37574.06064848401;38799.61200388073;38829;39021.414678780406;39992;37156.44265335968;37639.247215054995;37876.053098671655;38012.04969875775;38510;32855.83572082124;33766.66233337733;34376.31247374989;34505.309893802485;34742.82628586841;25265;25310.873445632653;25346.493070138124;25652.61521077148;25829.225123245582 17:35:55 INFO - PROCESS | 2783 | |2;jquery.html;351.3535095424419;366.73228543163486;372.218341482886;376.0823590439328;400.1696479804407;15039.172845493493;15168.696626067473;16186.498125847344;21072.789272107275;21170.682439763415;20332.390028299174;20780.168793248253;21433.57132857342;21652.675209871864;21678.783212167873;5684.54523638108;5696.487316141536;5884.440978107073;5938.524918006549;5949.762009519614;6009.278886533622;6156.7537298508005;6163.137160797492;6163.383661633823;6174.7530098796215;3906.726529142947;4087.734297270683;4092.4270602115726;4118.505779306487;4308.375285583604;4377.978110109445;6655.800325990228;6696.765613203539;6761.9323806761795;6787.694553745044;4523.389342438762;5416.539594134503;5469.671819690791;5980.043193089128;6008.008678568031;13323.26722030288;13379.464821407131;13423.261720605362;13588.524401645995;13593.59219223425;2112.3768488295837;2216.866987980726;2266.456050547873;2319.2312838531084;2870.9138725838257;9872.40765554069;9877.90122098777;9913.553890074963;10423.895761042368;10443.47782610874;1709.5555155659495;1716.648087142133;1736.965260694779;1839.1447976690904;1860.4697661166504;110307.44846275798;114660;115651;120391.39804301011;120754.79245207523;9785.608575657036;11198;11340.092792576574;11436.142289328334;11526.020288275422;14362.640933976734;14374.281285935662;14377.56867293983;14382.137071775727;14460.421583136767;8068.9596552016865;8337;8371.790705232417;8441.198086181803;8493.405461617627;7952.1650226726515;7985.2014798519685;8059.43583949118;8101.797455063671;8366.498010119412;3998.5801490843623;4518.774061296922;4531.365608814768;4533.116042371772;4601.240795268744;1852.036940790808;1903.9143238554298;1907.380101467001;1907.990460047691;1914.7989461106663;820;892.2950868813695;941.6798288581915;982.5578489679673;997.1275134257525;20315.68785517385;21146.577068458544;21202.575948480942;21212;21629;875.912408759119;915.1763412928285;937.4750139921666;952.4285428742837;985.610683779903;10789.730256743487;11123.721906952229;11275.436228188557;11307.208495405243;11372.715682108013;516299;543052;561164;576189;577761;125790;130712;133086;133889;134391;961.7980224152866;971.8639390485338;1000.0749306891097;1001.1990407673832;1001.4742260313394;18626.534336641693;19954.30159944395;19970.30103946355;20045.797252164917;20055.398338049883;56912;57056.42943570511;57461;57845.421545785684;57881.710591446776;64125;83774;84198;84305.15694843218;84336.15663843283;6983.266756990442;7867.8819817703925;8167.3466122711225;8256.669733210605;8430.789230269047;11727.530898764295;12913.548025819051;12914.160579562298;13328.067035307435;13337.799933001206;11173.608923687632;11369.943150284196;11511.884881151082;11599.130065244972;11622.128340374336;10842.891571084188;10848.728781780519;10898.836517452402;11220.214584978972;11359.375234362189;11426.200165988634;11593.594224202112;11603.477843497232;11699.707507312385;11812.82280765772;8147.470414423044;8178.263956243966;8180.754577362689;8185.4270201085355;8224.342052635895;8076.596170191349;11227.21409501327;11375.601853935073;11432.199746018032;11527.711807204887 17:35:55 INFO - PROCESS | 2783 | |3;mootools.html;1341.7786065299226;1351.8648135186477;1535.677507723378;1699.286299754103;1935.0421541337032;7739.458237923348;8211.589420528971;8281.792955176124;8539.316854651628;8547.273481754042;8356.665733370657;8461.196186362286;8469;8469.91530084699;8481;2278.612635851909;2309.3418375762913;2310.145246258878;2313.791758741713;2315.8842057897123;2261;2291.255342013844;2302.838801283911;2305.939267936751;2313;2190.627593309141;2214.512807182425;2218.1460137846966;2237.474193564511;2249.5388445368662;2209.657503087024;2272.136588096529;2274.40865375002;2278.4303924018996;2283.0183021300836;2239.2722365231202;2242.585121752481;2248.977510224893;2250.8199344052437;2250.8987095580737;8425.95787021061;8507.064222935473;8509.787255318604;8518.574071296473;8605.39762216645;4996.2755400467095;5198.142306519419;5201.8959620807755;5207.453217412155;5210.244514545408;3349.765516413852;3512.666296701809;3578.534790477221;3586.1572530455323;3600.945985810215;1012.4279781923263;1045.409343720803;1063.648995831374;1083.5882364701442;1084.4469320646526;9857;11362.71593210168;11470.65588032352;11470.827937580943;11481.770364592745;4258.936115958263;4323.373110898902;4495.820167193341;4506.188886000486;4518.887027824265;8699;8713.128687131237;8723.127687231354;8729.519876406732;8730.301575873915;5121.129408000649;5128.282040514331;5131.204663277198;5133.768980395891;5139.948600514022;4076.7553946763287;4335.30635098389;4436.046250056189;4491.079328737601;4825.541573550507;3095.3654500827274;3640.6177351377723;3694.131879008431;3695.4826324314618;3700.9259814803554;1113.2374323588367;1117.8323251512174;1121.1927412263158;1126.3692332293942;1135.5855112883903;910.9681161159327;915.4507295622573;917.9403338782957;942.3155462142988;943.9764005899907;5597.468240517144;5805.854853628609;5908.940910590925;6005.279366476051;6077.4834139098675;928.8049509602924;969.637659088967;980.367662857453;989.3272574649308;1011.9038691324313;3712.5892160979197;3868.400397938324;3919.6668283195677;3963.227170701743;4059.7970101494807;10301.227407944434;10605.09856662177;10610.045095941403;10639.787204255872;10648.893511064945;10224.693259202237;10405.9479702601;10614.946925265323;10647.414392208348;10648.201384896009;7731.680495851222;7902.446828721934;8052;8064.596770161468;8076.152004039609;5669.149627555899;5749.878773639268;5819.272590926134;5853.590248682552;5891.263592050994;9521.285903557122;9784.315097943076;9803.166730827674;9855.162311203629;9864.85202721974;9142.040085790895;10028.147607453448;10041.046100620722;10077.949610251902;10092.293539452461;5778;6533.18335208099;6759.290274521301;6939.097917270924;7035.401990830836;7717.961410192913;8107.22981316774;8132.878006829784;8234.217749313806;8247.463914845515;6553.91860343038;7131.8930216045765;7358.521696089737;7423.51747136456;7439.330460258603;7196;7264.34620884123;7293.234210407831;7340.376068034063;7375.926240737738;6812.352826481477;6936.687849046906;6936.826579335557;6955.791326260221;7002.299770022957;4833.854984350475;4934.3585333907795;4967.925481117858;4983.401991761012;5145.639805213599;4863.562279394871;4906.6320025997475;4993.750312484289;4995.400551933792;5129.204973229155 17:35:55 INFO - PROCESS | 2783 | |4;prototype.html;268.2435531800323;274.6004563360311;295.50207899689013;305.1836337796394;306.37652377411945;4335.718178318407;4655.464621568521;4944.986277813049;5128.769205385757;5421.810236641718;4560.90878182437;5005.54950054495;5179.689218646884;5267.86830329242;5389.326334208224;3505.9824700876525;3921.313770090237;4034.9798251008706;4046.7571945683208;4096.685372764378;3571.4107172316535;4011.8395264189544;4028.6978476614227;4046.2514434829504;4054.1688953764556;3210.341879914612;3259.543663887058;3898.8440462381473;4022.8793136205813;4047.069174089961;3510.5611798525183;3588.3540962626716;3952.8616498422416;4075.510938687362;4092.3656833190903;3505.2463720300257;3508.0177550285707;3513.6135025147214;3520.5356250624664;3539.8407071681836;4312.849050283225;4640.582347588733;4885.047415753929;5069.375396591455;5189.922151167735;4252.702310838212;4375.053101369892;4546.727196368195;4647.465541462708;4695.741734204617;5610.961972035146;5663.244394237759;5664.433556644344;5690.374058853523;5733.655980641133;3884.6698030667144;3893.8442462301473;3900.902477438087;3903.126499280333;3910.569837317893;5123.897522049576;5167.870803229949;5430.456954304538;5491.615586908918;5907.7046147692445;3256.8371581421306;3749.812509374575;3784.4512545680723;3820.503334566489;3832.865849695247;3153.731932785739;3432.502287168347;3877.592852750477;3887.669548088444;3908.335582950905;3807.5430948286385;3845.5193100862393;4312.202242585069;4565.931511027337;4569.29175977724;5094.465081166424;5473;5598.692071936001;5617.494425501643;5647.842192350558;1149.9310041397375;1295.436485128972;1318.2485982989795;1327.7676406628802;1328.5150919914356;826.9090400055989;840.8066144786611;846.3609974469236;847.3560094328232;860.4106187261746;4845.6123510119105;5141.640085194001;5196.479707341936;5266.445773799781;5266.815661451831;5681.431856814285;5687.7156142192725;5696.743646535916;5722.828315150469;5733.591891405619;4400.515943246241;4543.886402839889;5058.402271885418;5228.091746512571;5270.393904700932;2652.9469410611678;2801.7478426941675;2999.8500074996164;3015.1256135720396;3018.894338698135;5071.54356107952;5469.535089517356;5654.3884992777685;5826.737796799154;5928.644281343133;5770.16332631766;5867.911981320283;5895.64626122434;5895.734691938873;5915.1718759373725;4759.904801904012;4935.950640493621;5043.2939388486375;5895.823125306248;5904.380040095814;4810.577878532199;5290.1800220965815;5463.918041229305;5585.832425027255;5731.627444216113;4808.374911261513;5599.636023658449;5647.322321321468;5668.433156684298;5691.772329106955;4840.322354870251;4887.144749668722;5698.487136157766;5851.64890106595;5883.882322353615;3552.28954209154;3714.57282412526;3818.3317919363444;3882.369404850026;3937.9409308860963;5443.319585051869;5775.21723271258;5778.913316300343;5848.970755146197;5863;5600.831975040755;5645.548356131418;5653.604247702621;5693.772249110155;5697;4302.849400270976;5095.515925987025;5210.879660872931;5652.208690783212;5705.400932902152;4533.705309155026;4759.239081539852;5127.974360128175;5559.33288005442;5572.91640625399;5230.529252367305;5489.64317319373;5542.646698747361;5542.806001789918;5566.805161819317;4856.223004319292;5412.512873841373;5503.504684578407;5517.227588137584;5527.633169795977 17:35:55 INFO - PROCESS | 2783 | |5;yui.html;660.2506155513488;686.8008277599496;727.3126895084148;749.5652521537509;778.4356341652302;15331.770023449642;16075.51773446797;16077.276522556484;16091.436799712012;16461.75307370389;14488.420463181486;14567.125972441634;14594.562163135122;14595.197264150467;14640.780388294157;694.7221111555368;714.6069661686078;878.9560521973908;888.5690440136528;890.1454603580538;712.5653351455608;865.273170536749;887.0995939121797;891.3582220801013;893.8480458322101;946.5030858799116;951.4291425144927;952.0669743651226;952.5856252530172;954.0983770337;945.654835984864;947.1049857884302;947.3557980573207;948.3456415073578;948.8624149498353;542.1082319584261;558.5057224356433;559.4824787071983;671.2012704881224;704.3344039882313;15017.24913754319;15464.226788660635;15465.536033919001;15491.922540387339;15498.457553985669;11880.346580938045;13601.115927464683;14225.715485690333;14232.644183895382;14406.855931440761;12573.052916153201;12590.748185036247;12759.425825837767;12761.808572871414;13278.46886124554;4459.821607135711;4471.6646251531265;4494.483134439549;4584.724916505021;4611.169989401907;115155;116202;116917;119284;121074.94625268789;10395.480225988671;10570.52432640533;10575;10980.945095274632;11092.057175140042;19793;20489.07799149012;20803.479913001993;20855.099051722835;20901.79098209029;7364.2635736427;7433.591152486665;7473.148952002976;7593.164751877289;7674.462787604925;4206.432131662186;4225.936610950838;4234.978825105854;4244.511881133709;4350.60844523988;3515.8066306352885;3638.6179451157777;3652.0139562318027;3653.1415117447377;3658.4695219193068;660.1220376898713;664.8902931016328;672.3914857054374;676.3439463720183;679.3376457953456;599.6807024546016;615.9384061593805;622.8318354044383;626.7806267806322;646.6443456099126;8421.242088211968;8874.023857375683;8934.50860202695;8946.731598052069;8985.775355616162;578.7540295374449;591.4617697894895;609.8536351275662;633.1262857256955;637.6461064109371;4168.708190426641;4350.73895566267;4366.737995720267;4393.592677345576;4503.234450143484;90721.54639226894;92887.24262555718;146715.2664236672;147426;156711;127744;129845.35077324553;131028;132334.99999999808;134199.3290033563;111886.44056779664;116211;116778.24832627518;118257;119269;8183.3453323732765;8450.746477605682;8461.788455288666;8620.439671421338;8658.48049117055;56269.71865140648;58676.70661646665;60484.69757651183;60835;63116.68441657762;78554.32527681226;84755.30489390054;88122.55938720265;92184;97097.51451242699;12658.063420215087;13038.282894440517;13479.19124852512;13670.11144275619;13995.300234988414;13188.538401155913;14371;14429.5770434921;14878.404863805326;15101.924490377478;13332.93333533326;13687.452501899812;13709.97478648246;13819.239941803296;14376.133360642376;13341.866581334063;13634.727305454035;13801.309934503435;13931.651708707363;14144.564223278177;13281.734365312834;13434.261115638734;13533.458661653423;13562.457501699822;13799.310034498034;10332;10661.946690266499;10973.451327433437;11066.9195984128;11221.382823944436;11222.607208747655;11246.83129753038;11337.489812958273;11453.541858325574;11486.425678716198 17:35:55 INFO - PROCESS | 2783 | __end_tp_report 17:35:55 INFO - PROCESS | 2783 | __start_cc_report 17:35:55 INFO - PROCESS | 2783 | _x_x_mozilla_cycle_collect,1807 17:35:55 INFO - PROCESS | 2783 | __end_cc_report 17:35:55 INFO - PROCESS | 2783 | __startTimestamp1477442155617__endTimestamp 17:35:55 INFO - PROCESS | 2783 | 17:35:55 INFO - PROCESS | 2783 | ------- Summary: start ------- 17:35:55 INFO - PROCESS | 2783 | Number of tests: 6 17:35:55 INFO - PROCESS | 2783 | 17:35:55 INFO - PROCESS | 2783 | [#0] dojo.html Cycles:165 Average:5699.03 Median:5922.05 stddev:637.33 (10.8%) stddev-sans-first:610.18 17:35:55 INFO - PROCESS | 2783 | Values: 3271.9 3279.5 3280.6 3321.7 3326.2 5540.6 5632.6 5662.8 5698.0 5711.9 5678.7 5689.9 5789.3 5805.2 5818.8 5079.4 5131.0 5139.1 5212.6 5216.3 5314.9 5331.4 5337.2 5339.8 5352.0 5128.3 5288.6 5327.9 5354.2 5391.6 5199.4 5237.1 5243.1 5253.6 5256.4 5162.4 5199.9 5200.9 5219.5 5228.2 5697.3 5706.3 5708.3 5711.2 5725.9 6153.9 6171.8 6189.8 6194.9 6205.0 6166.3 6169.6 6187.7 6235.2 6238.5 4927.2 5826.4 5827.2 5838.5 5878.9 6138.5 6202.6 6239.8 6252.6 6257.3 6098.4 6169.0 6179.6 6183.6 6187.4 6238.8 6238.9 6248.6 6256.9 6270.0 6074.8 6101.4 6102.5 6106.4 6224.8 6146.4 6206.8 6207.0 6211.3 6214.1 4878.8 4905.7 4913.0 4922.9 4949.6 3912.4 4417.4 4447.4 4448.4 4465.8 5626.3 5691.1 5692.9 5700.5 5722.3 4785.7 6145.6 6147.0 6152.3 6156.9 4883.0 5702.6 5774.4 5842.0 5848.6 5356.0 5400.0 5403.8 5404.6 5425.1 6190.5 6192.2 6192.5 6199.5 6212.8 6109.7 6111.1 6132.8 6139.9 6144.6 5294.5 6096.0 6098.6 6099.2 6103.0 5647.9 5861.8 6108.8 6134.4 6163.1 6144.0 6144.9 6153.0 6156.8 6166.3 5990.9 6079.3 6117.8 6124.7 6128.4 6044.9 6077.1 6079.6 6117.5 6136.6 5980.6 6003.9 6026.6 6031.2 6051.3 6104.8 6116.4 6129.1 6130.0 6133.2 5800.9 5883.4 5885.7 5922.1 5954.6 17:35:55 INFO - PROCESS | 2783 | 17:35:55 INFO - PROCESS | 2783 | [#1] ext.html Cycles:180 Average:71174.65 Median:37606.65 stddev:159921.34 (425.2%) stddev-sans-first:160281.81 17:35:55 INFO - PROCESS | 2783 | Values: 456.2 505.6 511.7 517.9 544.9 30801.2 31322.5 31336.8 31341.8 40069.8 40556.2 42727.6 42729.2 42758.8 42776.6 9580.7 9706.5 9726.5 9744.3 9745.9 8635.4 8828.7 9464.7 9603.0 11522.0 11485.0 11656.8 11694.5 11694.6 11753.2 11577.1 11690.6 11699.1 11707.5 11725.5 8523.4 8710.0 11367.5 11468.0 11560.7 40388.2 40507.2 40667.6 40698.4 40712.2 3351.6 3383.2 3440.9 3497.6 3694.2 25332.6 28542.0 29591.9 29766.7 29925.6 3003.7 3030.7 3112.1 3238.1 3256.0 474592.6 477103.0 479778.0 479922.0 480813.0 58162.7 58311.7 58399.0 58497.4 58623.8 54260.5 60440.0 60456.8 61476.0 61590.0 15201.9 15775.9 15834.8 15849.0 15867.0 12193.6 12330.2 12330.8 12352.4 12376.0 13874.1 14190.8 14194.5 15297.8 15919.1 5302.7 5930.6 5937.3 5939.5 5939.7 903.9 929.0 960.2 967.7 1003.6 21840.7 29452.7 29496.3 30197.8 30319.0 1006.4 1022.4 1066.2 1089.6 1111.0 13243.3 14745.6 15054.9 15778.4 16220.0 548954.0 622458.0 622799.0 623159.0 623222.0 50527.5 51306.7 57062.7 57675.4 58157.0 1318.3 1367.5 1367.8 1368.5 1368.7 29887.3 30019.2 30244.4 30294.1 30471.0 45759.0 46153.1 46224.5 46253.0 46304.5 613799.0 619704.0 631996.0 767404.0 770907.0 24470.8 24733.4 24784.8 25108.9 25154.5 45117.3 45385.8 45816.0 45845.3 45893.0 38597.5 38756.4 39100.4 39595.0 40042.8 37574.1 38799.6 38829.0 39021.4 39992.0 37156.4 37639.2 37876.1 38012.0 38510.0 32855.8 33766.7 34376.3 34505.3 34742.8 25265.0 25310.9 25346.5 25652.6 25829.2 17:35:55 INFO - PROCESS | 2783 | 17:35:55 INFO - PROCESS | 2783 | [#2] jquery.html Cycles:180 Average:33668.30 Median:2292.84 stddev:93319.01 (4070.0%) stddev-sans-first:93547.26 17:35:55 INFO - PROCESS | 2783 | Values: 351.4 366.7 372.2 376.1 400.2 15039.2 15168.7 16186.5 21072.8 21170.7 20332.4 20780.2 21433.6 21652.7 21678.8 5684.5 5696.5 5884.4 5938.5 5949.8 6009.3 6156.8 6163.1 6163.4 6174.8 3906.7 4087.7 4092.4 4118.5 4308.4 4378.0 6655.8 6696.8 6761.9 6787.7 4523.4 5416.5 5469.7 5980.0 6008.0 13323.3 13379.5 13423.3 13588.5 13593.6 2112.4 2216.9 2266.5 2319.2 2870.9 9872.4 9877.9 9913.6 10423.9 10443.5 1709.6 1716.6 1737.0 1839.1 1860.5 110307.4 114660.0 115651.0 120391.4 120754.8 9785.6 11198.0 11340.1 11436.1 11526.0 14362.6 14374.3 14377.6 14382.1 14460.4 8069.0 8337.0 8371.8 8441.2 8493.4 7952.2 7985.2 8059.4 8101.8 8366.5 3998.6 4518.8 4531.4 4533.1 4601.2 1852.0 1903.9 1907.4 1908.0 1914.8 820.0 892.3 941.7 982.6 997.1 20315.7 21146.6 21202.6 21212.0 21629.0 875.9 915.2 937.5 952.4 985.6 10789.7 11123.7 11275.4 11307.2 11372.7 516299.0 543052.0 561164.0 576189.0 577761.0 125790.0 130712.0 133086.0 133889.0 134391.0 961.8 971.9 1000.1 1001.2 1001.5 18626.5 19954.3 19970.3 20045.8 20055.4 56912.0 57056.4 57461.0 57845.4 57881.7 64125.0 83774.0 84198.0 84305.2 84336.2 6983.3 7867.9 8167.3 8256.7 8430.8 11727.5 12913.5 12914.2 13328.1 13337.8 11173.6 11369.9 11511.9 11599.1 11622.1 10842.9 10848.7 10898.8 11220.2 11359.4 11426.2 11593.6 11603.5 11699.7 11812.8 8147.5 8178.3 8180.8 8185.4 8224.3 8076.6 11227.2 11375.6 11432.2 11527.7 17:35:55 INFO - PROCESS | 2783 | 17:35:55 INFO - PROCESS | 2783 | [#3] mootools.html Cycles:180 Average:5516.88 Median:4994.58 stddev:3083.64 (61.7%) stddev-sans-first:3076.32 17:35:55 INFO - PROCESS | 2783 | Values: 1341.8 1351.9 1535.7 1699.3 1935.0 7739.5 8211.6 8281.8 8539.3 8547.3 8356.7 8461.2 8469.0 8469.9 8481.0 2278.6 2309.3 2310.1 2313.8 2315.9 2261.0 2291.3 2302.8 2305.9 2313.0 2190.6 2214.5 2218.1 2237.5 2249.5 2209.7 2272.1 2274.4 2278.4 2283.0 2239.3 2242.6 2249.0 2250.8 2250.9 8426.0 8507.1 8509.8 8518.6 8605.4 4996.3 5198.1 5201.9 5207.5 5210.2 3349.8 3512.7 3578.5 3586.2 3600.9 1012.4 1045.4 1063.6 1083.6 1084.4 9857.0 11362.7 11470.7 11470.8 11481.8 4258.9 4323.4 4495.8 4506.2 4518.9 8699.0 8713.1 8723.1 8729.5 8730.3 5121.1 5128.3 5131.2 5133.8 5139.9 4076.8 4335.3 4436.0 4491.1 4825.5 3095.4 3640.6 3694.1 3695.5 3700.9 1113.2 1117.8 1121.2 1126.4 1135.6 911.0 915.5 917.9 942.3 944.0 5597.5 5805.9 5908.9 6005.3 6077.5 928.8 969.6 980.4 989.3 1011.9 3712.6 3868.4 3919.7 3963.2 4059.8 10301.2 10605.1 10610.0 10639.8 10648.9 10224.7 10405.9 10614.9 10647.4 10648.2 7731.7 7902.4 8052.0 8064.6 8076.2 5669.1 5749.9 5819.3 5853.6 5891.3 9521.3 9784.3 9803.2 9855.2 9864.9 9142.0 10028.1 10041.0 10077.9 10092.3 5778.0 6533.2 6759.3 6939.1 7035.4 7718.0 8107.2 8132.9 8234.2 8247.5 6553.9 7131.9 7358.5 7423.5 7439.3 7196.0 7264.3 7293.2 7340.4 7375.9 6812.4 6936.7 6936.8 6955.8 7002.3 4833.9 4934.4 4967.9 4983.4 5145.6 4863.6 4906.6 4993.8 4995.4 5129.2 17:35:55 INFO - PROCESS | 2783 | 17:35:55 INFO - PROCESS | 2783 | [#4] prototype.html Cycles:180 Average:4463.96 Median:5024.42 stddev:1389.07 (27.6%) stddev-sans-first:1356.80 17:35:55 INFO - PROCESS | 2783 | Values: 268.2 274.6 295.5 305.2 306.4 4335.7 4655.5 4945.0 5128.8 5421.8 4560.9 5005.5 5179.7 5267.9 5389.3 3506.0 3921.3 4035.0 4046.8 4096.7 3571.4 4011.8 4028.7 4046.3 4054.2 3210.3 3259.5 3898.8 4022.9 4047.1 3510.6 3588.4 3952.9 4075.5 4092.4 3505.2 3508.0 3513.6 3520.5 3539.8 4312.8 4640.6 4885.0 5069.4 5189.9 4252.7 4375.1 4546.7 4647.5 4695.7 5611.0 5663.2 5664.4 5690.4 5733.7 3884.7 3893.8 3900.9 3903.1 3910.6 5123.9 5167.9 5430.5 5491.6 5907.7 3256.8 3749.8 3784.5 3820.5 3832.9 3153.7 3432.5 3877.6 3887.7 3908.3 3807.5 3845.5 4312.2 4565.9 4569.3 5094.5 5473.0 5598.7 5617.5 5647.8 1149.9 1295.4 1318.2 1327.8 1328.5 826.9 840.8 846.4 847.4 860.4 4845.6 5141.6 5196.5 5266.4 5266.8 5681.4 5687.7 5696.7 5722.8 5733.6 4400.5 4543.9 5058.4 5228.1 5270.4 2652.9 2801.7 2999.9 3015.1 3018.9 5071.5 5469.5 5654.4 5826.7 5928.6 5770.2 5867.9 5895.6 5895.7 5915.2 4759.9 4936.0 5043.3 5895.8 5904.4 4810.6 5290.2 5463.9 5585.8 5731.6 4808.4 5599.6 5647.3 5668.4 5691.8 4840.3 4887.1 5698.5 5851.6 5883.9 3552.3 3714.6 3818.3 3882.4 3937.9 5443.3 5775.2 5778.9 5849.0 5863.0 5600.8 5645.5 5653.6 5693.8 5697.0 4302.8 5095.5 5210.9 5652.2 5705.4 4533.7 4759.2 5128.0 5559.3 5572.9 5230.5 5489.6 5542.6 5542.8 5566.8 4856.2 5412.5 5503.5 5517.2 5527.6 17:35:55 INFO - PROCESS | 2783 | 17:35:55 INFO - PROCESS | 2783 | [#5] yui.html Cycles:180 Average:24535.57 Median:3652.58 stddev:38963.10 (1066.7%) stddev-sans-first:39031.16 17:35:55 INFO - PROCESS | 2783 | Values: 660.3 686.8 727.3 749.6 778.4 15331.8 16075.5 16077.3 16091.4 16461.8 14488.4 14567.1 14594.6 14595.2 14640.8 694.7 714.6 879.0 888.6 890.1 712.6 865.3 887.1 891.4 893.8 946.5 951.4 952.1 952.6 954.1 945.7 947.1 947.4 948.3 948.9 542.1 558.5 559.5 671.2 704.3 15017.2 15464.2 15465.5 15491.9 15498.5 11880.3 13601.1 14225.7 14232.6 14406.9 12573.1 12590.7 12759.4 12761.8 13278.5 4459.8 4471.7 4494.5 4584.7 4611.2 115155.0 116202.0 116917.0 119284.0 121074.9 10395.5 10570.5 10575.0 10980.9 11092.1 19793.0 20489.1 20803.5 20855.1 20901.8 7364.3 7433.6 7473.1 7593.2 7674.5 4206.4 4225.9 4235.0 4244.5 4350.6 3515.8 3638.6 3652.0 3653.1 3658.5 660.1 664.9 672.4 676.3 679.3 599.7 615.9 622.8 626.8 646.6 8421.2 8874.0 8934.5 8946.7 8985.8 578.8 591.5 609.9 633.1 637.6 4168.7 4350.7 4366.7 4393.6 4503.2 90721.5 92887.2 146715.3 147426.0 156711.0 127744.0 129845.4 131028.0 132335.0 134199.3 111886.4 116211.0 116778.2 118257.0 119269.0 8183.3 8450.7 8461.8 8620.4 8658.5 56269.7 58676.7 60484.7 60835.0 63116.7 78554.3 84755.3 88122.6 92184.0 97097.5 12658.1 13038.3 13479.2 13670.1 13995.3 13188.5 14371.0 14429.6 14878.4 15101.9 13332.9 13687.5 13710.0 13819.2 14376.1 13341.9 13634.7 13801.3 13931.7 14144.6 13281.7 13434.3 13533.5 13562.5 13799.3 10332.0 10661.9 10973.5 11066.9 11221.4 11222.6 11246.8 11337.5 11453.5 11486.4 17:35:55 INFO - PROCESS | 2783 | -------- Summary: end -------- 17:35:55 INFO - PROCESS | 2783 | 17:35:56 INFO - TEST-INFO | 2783: exit 0 17:35:56 INFO - TEST-OK | dromaeo_css | took 1120183ms 17:35:56 INFO - TEST-START | kraken 17:35:56 INFO - operating with platform_type : mac_ 17:35:56 INFO - Initialising browser for kraken test... 17:35:56 INFO - TEST-INFO | started process 2789 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpQf9Snu/profile http://localhost:50285/getInfo.html) 17:35:58 INFO - PROCESS | 2789 | __metrics Screen width/height:1600/1200 17:35:58 INFO - PROCESS | 2789 | colorDepth:24 17:35:58 INFO - PROCESS | 2789 | Browser inner width/height: 1024/689 17:35:58 INFO - PROCESS | 2789 | __metrics 17:35:58 INFO - PROCESS | 2789 | 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" 17:35:58 INFO - PROCESS | 2789 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:35:59 INFO - TEST-INFO | 2789: exit 0 17:35:59 INFO - Browser initialized. 17:35:59 INFO - Running cycle 1/1 for kraken test... 17:35:59 INFO - TEST-INFO | started process 2790 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpQf9Snu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:36:11 INFO - PROCESS | 2790 | RSS: Main: 233488384 17:36:11 INFO - PROCESS | 2790 | 17:36:14 INFO - PROCESS | 2790 | Cycle 1(1): loaded http://localhost:50285/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50285/tests/kraken/driver.html?testName=audio-beat-detection) 17:36:15 INFO - PROCESS | 2790 | RSS: Main: 291618816 17:36:15 INFO - PROCESS | 2790 | 17:36:18 INFO - PROCESS | 2790 | Cycle 1(1): loaded http://localhost:50285/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50285/tests/kraken/driver.html?testName=audio-dft) 17:36:18 INFO - PROCESS | 2790 | RSS: Main: 293351424 17:36:18 INFO - PROCESS | 2790 | 17:36:22 INFO - PROCESS | 2790 | Cycle 1(1): loaded http://localhost:50285/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50285/tests/kraken/driver.html?testName=audio-fft) 17:36:23 INFO - PROCESS | 2790 | RSS: Main: 385331200 17:36:23 INFO - PROCESS | 2790 | 17:36:25 INFO - PROCESS | 2790 | Cycle 1(1): loaded http://localhost:50285/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50285/tests/kraken/driver.html?testName=audio-oscillator) 17:36:25 INFO - PROCESS | 2790 | RSS: Main: 287551488 17:36:25 INFO - PROCESS | 2790 | 17:36:27 INFO - PROCESS | 2790 | Cycle 1(1): loaded http://localhost:50285/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50285/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:36:28 INFO - PROCESS | 2790 | RSS: Main: 292237312 17:36:28 INFO - PROCESS | 2790 | 17:36:30 INFO - PROCESS | 2790 | Cycle 1(1): loaded http://localhost:50285/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50285/tests/kraken/driver.html?testName=imaging-darkroom) 17:36:31 INFO - PROCESS | 2790 | RSS: Main: 329744384 17:36:31 INFO - PROCESS | 2790 | 17:36:34 INFO - PROCESS | 2790 | Cycle 1(1): loaded http://localhost:50285/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50285/tests/kraken/driver.html?testName=imaging-desaturate) 17:36:35 INFO - PROCESS | 2790 | RSS: Main: 331173888 17:36:35 INFO - PROCESS | 2790 | 17:36:37 INFO - PROCESS | 2790 | Cycle 1(1): loaded http://localhost:50285/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50285/tests/kraken/driver.html?testName=json-parse-financial) 17:36:38 INFO - PROCESS | 2790 | RSS: Main: 329408512 17:36:38 INFO - PROCESS | 2790 | 17:36:39 INFO - PROCESS | 2790 | Cycle 1(1): loaded http://localhost:50285/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50285/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:36:40 INFO - PROCESS | 2790 | RSS: Main: 301432832 17:36:40 INFO - PROCESS | 2790 | 17:36:42 INFO - PROCESS | 2790 | Cycle 1(1): loaded http://localhost:50285/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50285/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:36:43 INFO - PROCESS | 2790 | RSS: Main: 304164864 17:36:43 INFO - PROCESS | 2790 | 17:36:45 INFO - PROCESS | 2790 | Cycle 1(1): loaded http://localhost:50285/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50285/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:36:46 INFO - PROCESS | 2790 | RSS: Main: 313004032 17:36:46 INFO - PROCESS | 2790 | 17:36:49 INFO - PROCESS | 2790 | Cycle 1(1): loaded http://localhost:50285/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50285/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:36:49 INFO - PROCESS | 2790 | RSS: Main: 316592128 17:36:49 INFO - PROCESS | 2790 | 17:36:52 INFO - PROCESS | 2790 | Cycle 1(1): loaded http://localhost:50285/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50285/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:36:53 INFO - PROCESS | 2790 | RSS: Main: 317616128 17:36:53 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | Cycle 1(1): loaded http://localhost:50285/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50285/tests/kraken/driver.html?testName=ai-astar) 17:36:55 INFO - PROCESS | 2790 | RSS: Main: 361484288 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | __start_tp_report 17:36:55 INFO - PROCESS | 2790 | _x_x_mozilla_page_load 17:36:55 INFO - PROCESS | 2790 | _x_x_mozilla_page_load_details 17:36:55 INFO - PROCESS | 2790 | |i|pagename|runs| 17:36:55 INFO - PROCESS | 2790 | |0;ai-astar;114;162;161;113;113;113;111;110;113;112 17:36:55 INFO - PROCESS | 2790 | |1;audio-beat-detection;153;112;114;151;117;132;134;115;144;113 17:36:55 INFO - PROCESS | 2790 | |2;audio-dft;183;185;186;185;187;186;184;186;180;184 17:36:55 INFO - PROCESS | 2790 | |3;audio-fft;80;59;59;89;60;59;59;59;84;58 17:36:55 INFO - PROCESS | 2790 | |4;audio-oscillator;82;71;93;70;69;69;94;68;70;91 17:36:55 INFO - PROCESS | 2790 | |5;imaging-gaussian-blur;87;84;86;83;84;95;86;84;86;82 17:36:55 INFO - PROCESS | 2790 | |6;imaging-darkroom;96;96;96;96;95;96;97;96;98;96 17:36:55 INFO - PROCESS | 2790 | |7;imaging-desaturate;82;83;80;80;80;81;84;87;65;94 17:36:55 INFO - PROCESS | 2790 | |8;json-parse-financial;57;55;53;53;53;54;54;55;53;53 17:36:55 INFO - PROCESS | 2790 | |9;json-stringify-tinderbox;64;77;79;74;71;70;69;71;69;71 17:36:55 INFO - PROCESS | 2790 | |10;stanford-crypto-aes;89;90;87;89;88;85;90;86;87;103 17:36:55 INFO - PROCESS | 2790 | |11;stanford-crypto-ccm;150;147;148;150;151;135;151;163;187;159 17:36:55 INFO - PROCESS | 2790 | |12;stanford-crypto-pbkdf2;174;176;176;175;175;180;220;218;165;227 17:36:55 INFO - PROCESS | 2790 | |13;stanford-crypto-sha256-iterative;64;67;86;91;84;84;77;68;69;68 17:36:55 INFO - PROCESS | 2790 | __end_tp_report 17:36:55 INFO - PROCESS | 2790 | __start_cc_report 17:36:55 INFO - PROCESS | 2790 | _x_x_mozilla_cycle_collect,2054 17:36:55 INFO - PROCESS | 2790 | __end_cc_report 17:36:55 INFO - PROCESS | 2790 | __startTimestamp1477442215614__endTimestamp 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | ------- Summary: start ------- 17:36:55 INFO - PROCESS | 2790 | Number of tests: 14 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | [#0] ai-astar Cycles:10 Average:122.20 Median:113.00 stddev:20.75 (18.4%) stddev-sans-first:21.79 17:36:55 INFO - PROCESS | 2790 | Values: 114.0 162.0 161.0 113.0 113.0 113.0 111.0 110.0 113.0 112.0 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:133.00 stddev:16.42 (12.3%) stddev-sans-first:14.83 17:36:55 INFO - PROCESS | 2790 | Values: 153.0 112.0 114.0 151.0 117.0 132.0 134.0 115.0 144.0 113.0 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.50 stddev:2.01 (1.1%) stddev-sans-first:2.05 17:36:55 INFO - PROCESS | 2790 | Values: 183.0 185.0 186.0 185.0 187.0 186.0 184.0 186.0 180.0 184.0 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | [#3] audio-fft Cycles:10 Average:66.60 Median:59.50 stddev:12.43 (20.9%) stddev-sans-first:12.20 17:36:55 INFO - PROCESS | 2790 | Values: 80.0 59.0 59.0 89.0 60.0 59.0 59.0 59.0 84.0 58.0 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:76.50 stddev:11.08 (14.5%) stddev-sans-first:11.64 17:36:55 INFO - PROCESS | 2790 | Values: 82.0 71.0 93.0 70.0 69.0 69.0 94.0 68.0 70.0 91.0 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.00 stddev:3.62 (4.2%) stddev-sans-first:3.81 17:36:55 INFO - PROCESS | 2790 | Values: 87.0 84.0 86.0 83.0 84.0 95.0 86.0 84.0 86.0 82.0 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | [#6] imaging-darkroom Cycles:10 Average:96.20 Median:96.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 17:36:55 INFO - PROCESS | 2790 | Values: 96.0 96.0 96.0 96.0 95.0 96.0 97.0 96.0 98.0 96.0 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | [#7] imaging-desaturate Cycles:10 Average:81.60 Median:82.50 stddev:7.26 (8.8%) stddev-sans-first:7.70 17:36:55 INFO - PROCESS | 2790 | Values: 82.0 83.0 80.0 80.0 80.0 81.0 84.0 87.0 65.0 94.0 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:1.33 (2.5%) stddev-sans-first:0.87 17:36:55 INFO - PROCESS | 2790 | Values: 57.0 55.0 53.0 53.0 53.0 54.0 54.0 55.0 53.0 53.0 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.00 stddev:4.28 (6.0%) stddev-sans-first:3.57 17:36:55 INFO - PROCESS | 2790 | Values: 64.0 77.0 79.0 74.0 71.0 70.0 69.0 71.0 69.0 71.0 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:88.50 stddev:5.06 (5.7%) stddev-sans-first:5.36 17:36:55 INFO - PROCESS | 2790 | Values: 89.0 90.0 87.0 89.0 88.0 85.0 90.0 86.0 87.0 103.0 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | [#11] stanford-crypto-ccm Cycles:10 Average:154.10 Median:151.00 stddev:13.71 (9.1%) stddev-sans-first:14.46 17:36:55 INFO - PROCESS | 2790 | Values: 150.0 147.0 148.0 150.0 151.0 135.0 151.0 163.0 187.0 159.0 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.60 Median:178.00 stddev:23.23 (13.1%) stddev-sans-first:24.03 17:36:55 INFO - PROCESS | 2790 | Values: 174.0 176.0 176.0 175.0 175.0 180.0 220.0 218.0 165.0 227.0 17:36:55 INFO - PROCESS | 2790 | 17:36:55 INFO - PROCESS | 2790 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.80 Median:80.50 stddev:9.75 (12.1%) stddev-sans-first:9.36 17:36:55 INFO - PROCESS | 2790 | Values: 64.0 67.0 86.0 91.0 84.0 84.0 77.0 68.0 69.0 68.0 17:36:55 INFO - PROCESS | 2790 | -------- Summary: end -------- 17:36:55 INFO - PROCESS | 2790 | 17:36:56 INFO - TEST-INFO | 2790: exit 0 17:36:56 INFO - TEST-OK | kraken | took 60254ms 17:36:56 INFO - SUITE-END | took 1180s 17:36:56 INFO - Completed test suite (00:19:40) 17:36:56 INFO - javascript benchmark 17:36:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3271.9182020449502, 3279.5408642790007, 3280.606327240731, 3321.7342612590996, 3326.2349659578294, 5540.584456165793, 5632.577556683244, 5662.801801936933, 5698.031334673105, 5711.885762284752, 5678.744456499458, 5689.886202275962, 5789.2763404574425, 5805.24531810864, 5818.7963421280265, 5079.3650793650795, 5131.0, 5139.0749665060275, 5212.609054320922, 5216.295800067, 5314.867128321785, 5331.360236771593, 5337.172738225562, 5339.813106541272, 5352.036633405985, 5128.25640282157, 5288.550473209782, 5327.866803329928, 5354.196870469422, 5391.622586418952, 5199.402068762103, 5237.083510385705, 5243.056249875021, 5253.553447956928, 5256.395514515803, 5162.380514338266, 5199.922001169985, 5200.947990520084, 5219.530242278213, 5228.189630607262, 5697.287839020122, 5706.343770466408, 5708.257926469532, 5711.20043193949, 5725.885482290372, 6153.876922461571, 6171.7531298748445, 6189.783357582463, 6194.907076393858, 6204.9689751551405, 6166.260048794128, 6169.629822210637, 6187.659678717669, 6235.158253635746, 6238.4697300729495, 4927.236278376858, 5826.359100498942, 5827.172271245729, 5838.5329173666005, 5878.911816322801, 6138.457541419318, 6202.627842329474, 6239.812805615838, 6252.624842509464, 6257.342978987232, 6098.390160983866, 6168.9691551541955, 6179.62922224668, 6183.628982261079, 6187.443130118311, 6238.781642642486, 6238.875222495525, 6248.562600618004, 6256.906146407808, 6270.028145637433, 6074.787382441682, 6101.4290607115445, 6102.479535881749, 6106.36966090081, 6224.751009959551, 6146.416090471398, 6206.813795586139, 6207.006878899354, 6211.2857021443115, 6214.067889816563, 4878.756062196876, 4905.7301848397965, 4912.9508704913205, 4922.90154196914, 4949.554540091337, 3912.3935789952034, 4417.359482875029, 4447.355133505624, 4448.354988526646, 4465.776711164429, 5626.2967129108865, 5691.060974939089, 5692.857678557986, 5700.515456186187, 5722.341930678025, 4785.736784476818, 6145.631262124287, 6147.0, 6152.323244443108, 6156.8768624627255, 4882.975585122052, 5702.572307076985, 5774.364819869811, 5842.0, 5848.561357898175, 5355.973220133875, 5400.0, 5403.83788486346, 5404.594655400861, 5425.104857698436, 6190.50475961931, 6192.195014648157, 6192.473639740672, 6199.535034872402, 6212.751489940351, 6109.7387008107025, 6111.083337499411, 6132.78535251264, 6139.938600614116, 6144.631322120687, 5294.549963253013, 6096.0429282035075, 6098.613770999223, 6099.24925446448, 6102.974033066949, 5647.887042259214, 5861.827634473208, 6108.797423689751, 6134.35589263121, 6163.137160797582, 6144.0, 6144.938550614436, 6153.046277826944, 6156.846078847885, 6166.2600487939935, 5990.850228744316, 6079.300880398634, 6117.755289788537, 6124.724387402489, 6128.448439640454, 6044.90932636002, 6077.11881777131, 6079.635221886701, 6117.462039585284, 6136.6011209271255, 5980.5514586405325, 6003.949308870843, 6026.578139530191, 6031.24609423822, 6051.3041000283765, 6104.755809767737, 6116.449519543263, 6129.1112788644505, 6129.969350153399, 6133.233345831771, 5800.9419905800405, 5883.352831188651, 5885.73514191871, 5922.052471604523, 5954.612950158399], "value": 5658.595739651539, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.2221412491702, 505.58289411235734, 511.74668539073167, 517.9300794392758, 544.8964696707628, 30801.22996925078, 31322.53016204756, 31336.84331578339, 31341.84329078354, 40069.799651001704, 40556.18887622253, 42727.572724272744, 42729.23155374024, 42758.786206069, 42776.57223427765, 9580.664676736316, 9706.514674266295, 9726.513674316291, 9744.31789774716, 9745.853812192823, 8635.352348573851, 8828.73513794584, 9464.716058518221, 9602.951985240099, 11522.0, 11485.0, 11656.7668646627, 11694.532218711283, 11694.649160525198, 11753.177277590572, 11577.131715121399, 11690.590829320932, 11699.122565807598, 11707.473163707653, 11725.530978760838, 8523.445976011539, 8710.0, 11367.545298188063, 11468.0, 11560.6531804046, 40388.19223615541, 40507.18985620271, 40667.59332406668, 40698.38952415687, 40712.185756285005, 3351.597808263024, 3383.2218589724257, 3440.9483857742152, 3497.632748561415, 3694.242680250542, 25332.620010699855, 28542.00102996406, 29591.852040739876, 29766.70233297661, 29925.551116733266, 3003.6996300370006, 3030.7423868971164, 3112.1286039908637, 3238.141892398516, 3255.9837200813845, 474592.62703686603, 477103.0, 479778.0, 479922.0, 480813.0, 58162.709186453794, 58311.70844145752, 58399.0, 58497.41502584919, 58623.82752344929, 54260.45739542554, 60440.0, 60456.79086418247, 61476.0, 61589.9933008706, 15201.923990380199, 15775.921120394325, 15834.76247856283, 15848.96981696186, 15866.968647038137, 12193.634190974284, 12330.19853709506, 12330.81503777444, 12352.44414001372, 12376.0, 13874.098183618033, 14190.787138192936, 14194.503192388216, 15297.847021529642, 15919.099820544041, 5302.655327403706, 5930.584859059824, 5937.2578427696535, 5939.524838012949, 5939.7229595637145, 903.9378729992233, 929.0384452092052, 960.1708744841089, 967.7387105481621, 1003.5734812704571, 21840.672389914165, 29452.705472945425, 29496.262593435335, 30197.849010755246, 30319.0, 1006.3911333643041, 1022.4427686910503, 1066.1630619963291, 1089.5968491658136, 1110.9888901111046, 13243.337833108306, 14745.557633271019, 15054.924725376522, 15778.447754328772, 16220.0, 548954.0, 622458.0, 622799.0, 623159.0, 623222.0, 50527.49472505302, 51306.74346628243, 57062.714686427134, 57675.423245767844, 58157.0, 1318.2881244128255, 1367.5076972289967, 1367.8358596968228, 1368.4526189524072, 1368.6920442900268, 29887.252818679706, 30019.249518762204, 30244.395112098075, 30294.091177264716, 30471.0, 45759.0, 46153.07693846037, 46224.537754622026, 46253.0, 46304.53695463002, 613799.0, 619704.0, 631996.0, 767404.0, 770907.0, 24470.755292446847, 24733.38166545779, 24784.752152478966, 25108.874455627603, 25154.49691006133, 45117.32324015077, 45385.773071134434, 45816.0, 45845.31232031589, 45893.0, 38597.45610175674, 38756.41865372078, 39100.413493797045, 39595.0, 40042.79978600088, 37574.06064848401, 38799.61200388073, 38829.0, 39021.414678780406, 39992.0, 37156.44265335968, 37639.247215054995, 37876.053098671655, 38012.04969875775, 38510.0, 32855.83572082124, 33766.66233337733, 34376.31247374989, 34505.309893802485, 34742.82628586841, 25265.0, 25310.873445632653, 25346.493070138124, 25652.61521077148, 25829.225123245582], "value": 19610.07959328267, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.3535095424419, 366.73228543163486, 372.218341482886, 376.0823590439328, 400.1696479804407, 15039.172845493493, 15168.696626067473, 16186.498125847344, 21072.789272107275, 21170.682439763415, 20332.390028299174, 20780.168793248253, 21433.57132857342, 21652.675209871864, 21678.783212167873, 5684.54523638108, 5696.487316141536, 5884.440978107073, 5938.524918006549, 5949.762009519614, 6009.278886533622, 6156.7537298508005, 6163.137160797492, 6163.383661633823, 6174.7530098796215, 3906.726529142947, 4087.734297270683, 4092.4270602115726, 4118.505779306487, 4308.375285583604, 4377.978110109445, 6655.800325990228, 6696.765613203539, 6761.9323806761795, 6787.694553745044, 4523.389342438762, 5416.539594134503, 5469.671819690791, 5980.043193089128, 6008.008678568031, 13323.26722030288, 13379.464821407131, 13423.261720605362, 13588.524401645995, 13593.59219223425, 2112.3768488295837, 2216.866987980726, 2266.456050547873, 2319.2312838531084, 2870.9138725838257, 9872.40765554069, 9877.90122098777, 9913.553890074963, 10423.895761042368, 10443.47782610874, 1709.5555155659495, 1716.648087142133, 1736.965260694779, 1839.1447976690904, 1860.4697661166504, 110307.44846275798, 114660.0, 115651.0, 120391.39804301011, 120754.79245207523, 9785.608575657036, 11198.0, 11340.092792576574, 11436.142289328334, 11526.020288275422, 14362.640933976734, 14374.281285935662, 14377.56867293983, 14382.137071775727, 14460.421583136767, 8068.9596552016865, 8337.0, 8371.790705232417, 8441.198086181803, 8493.405461617627, 7952.1650226726515, 7985.2014798519685, 8059.43583949118, 8101.797455063671, 8366.498010119412, 3998.5801490843623, 4518.774061296922, 4531.365608814768, 4533.116042371772, 4601.240795268744, 1852.036940790808, 1903.9143238554298, 1907.380101467001, 1907.990460047691, 1914.7989461106663, 820.0, 892.2950868813695, 941.6798288581915, 982.5578489679673, 997.1275134257525, 20315.68785517385, 21146.577068458544, 21202.575948480942, 21212.0, 21629.0, 875.912408759119, 915.1763412928285, 937.4750139921666, 952.4285428742837, 985.610683779903, 10789.730256743487, 11123.721906952229, 11275.436228188557, 11307.208495405243, 11372.715682108013, 516299.0, 543052.0, 561164.0, 576189.0, 577761.0, 125790.0, 130712.0, 133086.0, 133889.0, 134391.0, 961.7980224152866, 971.8639390485338, 1000.0749306891097, 1001.1990407673832, 1001.4742260313394, 18626.534336641693, 19954.30159944395, 19970.30103946355, 20045.797252164917, 20055.398338049883, 56912.0, 57056.42943570511, 57461.0, 57845.421545785684, 57881.710591446776, 64125.0, 83774.0, 84198.0, 84305.15694843218, 84336.15663843283, 6983.266756990442, 7867.8819817703925, 8167.3466122711225, 8256.669733210605, 8430.789230269047, 11727.530898764295, 12913.548025819051, 12914.160579562298, 13328.067035307435, 13337.799933001206, 11173.608923687632, 11369.943150284196, 11511.884881151082, 11599.130065244972, 11622.128340374336, 10842.891571084188, 10848.728781780519, 10898.836517452402, 11220.214584978972, 11359.375234362189, 11426.200165988634, 11593.594224202112, 11603.477843497232, 11699.707507312385, 11812.82280765772, 8147.470414423044, 8178.263956243966, 8180.754577362689, 8185.4270201085355, 8224.342052635895, 8076.596170191349, 11227.21409501327, 11375.601853935073, 11432.199746018032, 11527.711807204887], "value": 9304.125606672627, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.7786065299226, 1351.8648135186477, 1535.677507723378, 1699.286299754103, 1935.0421541337032, 7739.458237923348, 8211.589420528971, 8281.792955176124, 8539.316854651628, 8547.273481754042, 8356.665733370657, 8461.196186362286, 8469.0, 8469.91530084699, 8481.0, 2278.612635851909, 2309.3418375762913, 2310.145246258878, 2313.791758741713, 2315.8842057897123, 2261.0, 2291.255342013844, 2302.838801283911, 2305.939267936751, 2313.0, 2190.627593309141, 2214.512807182425, 2218.1460137846966, 2237.474193564511, 2249.5388445368662, 2209.657503087024, 2272.136588096529, 2274.40865375002, 2278.4303924018996, 2283.0183021300836, 2239.2722365231202, 2242.585121752481, 2248.977510224893, 2250.8199344052437, 2250.8987095580737, 8425.95787021061, 8507.064222935473, 8509.787255318604, 8518.574071296473, 8605.39762216645, 4996.2755400467095, 5198.142306519419, 5201.8959620807755, 5207.453217412155, 5210.244514545408, 3349.765516413852, 3512.666296701809, 3578.534790477221, 3586.1572530455323, 3600.945985810215, 1012.4279781923263, 1045.409343720803, 1063.648995831374, 1083.5882364701442, 1084.4469320646526, 9857.0, 11362.71593210168, 11470.65588032352, 11470.827937580943, 11481.770364592745, 4258.936115958263, 4323.373110898902, 4495.820167193341, 4506.188886000486, 4518.887027824265, 8699.0, 8713.128687131237, 8723.127687231354, 8729.519876406732, 8730.301575873915, 5121.129408000649, 5128.282040514331, 5131.204663277198, 5133.768980395891, 5139.948600514022, 4076.7553946763287, 4335.30635098389, 4436.046250056189, 4491.079328737601, 4825.541573550507, 3095.3654500827274, 3640.6177351377723, 3694.131879008431, 3695.4826324314618, 3700.9259814803554, 1113.2374323588367, 1117.8323251512174, 1121.1927412263158, 1126.3692332293942, 1135.5855112883903, 910.9681161159327, 915.4507295622573, 917.9403338782957, 942.3155462142988, 943.9764005899907, 5597.468240517144, 5805.854853628609, 5908.940910590925, 6005.279366476051, 6077.4834139098675, 928.8049509602924, 969.637659088967, 980.367662857453, 989.3272574649308, 1011.9038691324313, 3712.5892160979197, 3868.400397938324, 3919.6668283195677, 3963.227170701743, 4059.7970101494807, 10301.227407944434, 10605.09856662177, 10610.045095941403, 10639.787204255872, 10648.893511064945, 10224.693259202237, 10405.9479702601, 10614.946925265323, 10647.414392208348, 10648.201384896009, 7731.680495851222, 7902.446828721934, 8052.0, 8064.596770161468, 8076.152004039609, 5669.149627555899, 5749.878773639268, 5819.272590926134, 5853.590248682552, 5891.263592050994, 9521.285903557122, 9784.315097943076, 9803.166730827674, 9855.162311203629, 9864.85202721974, 9142.040085790895, 10028.147607453448, 10041.046100620722, 10077.949610251902, 10092.293539452461, 5778.0, 6533.18335208099, 6759.290274521301, 6939.097917270924, 7035.401990830836, 7717.961410192913, 8107.22981316774, 8132.878006829784, 8234.217749313806, 8247.463914845515, 6553.91860343038, 7131.8930216045765, 7358.521696089737, 7423.51747136456, 7439.330460258603, 7196.0, 7264.34620884123, 7293.234210407831, 7340.376068034063, 7375.926240737738, 6812.352826481477, 6936.687849046906, 6936.826579335557, 6955.791326260221, 7002.299770022957, 4833.854984350475, 4934.3585333907795, 4967.925481117858, 4983.401991761012, 5145.639805213599, 4863.562279394871, 4906.6320025997475, 4993.750312484289, 4995.400551933792, 5129.204973229155], "value": 4443.225193181526, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.2435531800323, 274.6004563360311, 295.50207899689013, 305.1836337796394, 306.37652377411945, 4335.718178318407, 4655.464621568521, 4944.986277813049, 5128.769205385757, 5421.810236641718, 4560.90878182437, 5005.54950054495, 5179.689218646884, 5267.86830329242, 5389.326334208224, 3505.9824700876525, 3921.313770090237, 4034.9798251008706, 4046.7571945683208, 4096.685372764378, 3571.4107172316535, 4011.8395264189544, 4028.6978476614227, 4046.2514434829504, 4054.1688953764556, 3210.341879914612, 3259.543663887058, 3898.8440462381473, 4022.8793136205813, 4047.069174089961, 3510.5611798525183, 3588.3540962626716, 3952.8616498422416, 4075.510938687362, 4092.3656833190903, 3505.2463720300257, 3508.0177550285707, 3513.6135025147214, 3520.5356250624664, 3539.8407071681836, 4312.849050283225, 4640.582347588733, 4885.047415753929, 5069.375396591455, 5189.922151167735, 4252.702310838212, 4375.053101369892, 4546.727196368195, 4647.465541462708, 4695.741734204617, 5610.961972035146, 5663.244394237759, 5664.433556644344, 5690.374058853523, 5733.655980641133, 3884.6698030667144, 3893.8442462301473, 3900.902477438087, 3903.126499280333, 3910.569837317893, 5123.897522049576, 5167.870803229949, 5430.456954304538, 5491.615586908918, 5907.7046147692445, 3256.8371581421306, 3749.812509374575, 3784.4512545680723, 3820.503334566489, 3832.865849695247, 3153.731932785739, 3432.502287168347, 3877.592852750477, 3887.669548088444, 3908.335582950905, 3807.5430948286385, 3845.5193100862393, 4312.202242585069, 4565.931511027337, 4569.29175977724, 5094.465081166424, 5473.0, 5598.692071936001, 5617.494425501643, 5647.842192350558, 1149.9310041397375, 1295.436485128972, 1318.2485982989795, 1327.7676406628802, 1328.5150919914356, 826.9090400055989, 840.8066144786611, 846.3609974469236, 847.3560094328232, 860.4106187261746, 4845.6123510119105, 5141.640085194001, 5196.479707341936, 5266.445773799781, 5266.815661451831, 5681.431856814285, 5687.7156142192725, 5696.743646535916, 5722.828315150469, 5733.591891405619, 4400.515943246241, 4543.886402839889, 5058.402271885418, 5228.091746512571, 5270.393904700932, 2652.9469410611678, 2801.7478426941675, 2999.8500074996164, 3015.1256135720396, 3018.894338698135, 5071.54356107952, 5469.535089517356, 5654.3884992777685, 5826.737796799154, 5928.644281343133, 5770.16332631766, 5867.911981320283, 5895.64626122434, 5895.734691938873, 5915.1718759373725, 4759.904801904012, 4935.950640493621, 5043.2939388486375, 5895.823125306248, 5904.380040095814, 4810.577878532199, 5290.1800220965815, 5463.918041229305, 5585.832425027255, 5731.627444216113, 4808.374911261513, 5599.636023658449, 5647.322321321468, 5668.433156684298, 5691.772329106955, 4840.322354870251, 4887.144749668722, 5698.487136157766, 5851.64890106595, 5883.882322353615, 3552.28954209154, 3714.57282412526, 3818.3317919363444, 3882.369404850026, 3937.9409308860963, 5443.319585051869, 5775.21723271258, 5778.913316300343, 5848.970755146197, 5863.0, 5600.831975040755, 5645.548356131418, 5653.604247702621, 5693.772249110155, 5697.0, 4302.849400270976, 5095.515925987025, 5210.879660872931, 5652.208690783212, 5705.400932902152, 4533.705309155026, 4759.239081539852, 5127.974360128175, 5559.33288005442, 5572.91640625399, 5230.529252367305, 5489.64317319373, 5542.646698747361, 5542.806001789918, 5566.805161819317, 4856.223004319292, 5412.512873841373, 5503.504684578407, 5517.227588137584, 5527.633169795977], "value": 4016.2778660409804, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [660.2506155513488, 686.8008277599496, 727.3126895084148, 749.5652521537509, 778.4356341652302, 15331.770023449642, 16075.51773446797, 16077.276522556484, 16091.436799712012, 16461.75307370389, 14488.420463181486, 14567.125972441634, 14594.562163135122, 14595.197264150467, 14640.780388294157, 694.7221111555368, 714.6069661686078, 878.9560521973908, 888.5690440136528, 890.1454603580538, 712.5653351455608, 865.273170536749, 887.0995939121797, 891.3582220801013, 893.8480458322101, 946.5030858799116, 951.4291425144927, 952.0669743651226, 952.5856252530172, 954.0983770337, 945.654835984864, 947.1049857884302, 947.3557980573207, 948.3456415073578, 948.8624149498353, 542.1082319584261, 558.5057224356433, 559.4824787071983, 671.2012704881224, 704.3344039882313, 15017.24913754319, 15464.226788660635, 15465.536033919001, 15491.922540387339, 15498.457553985669, 11880.346580938045, 13601.115927464683, 14225.715485690333, 14232.644183895382, 14406.855931440761, 12573.052916153201, 12590.748185036247, 12759.425825837767, 12761.808572871414, 13278.46886124554, 4459.821607135711, 4471.6646251531265, 4494.483134439549, 4584.724916505021, 4611.169989401907, 115155.0, 116202.0, 116917.0, 119284.0, 121074.94625268789, 10395.480225988671, 10570.52432640533, 10575.0, 10980.945095274632, 11092.057175140042, 19793.0, 20489.07799149012, 20803.479913001993, 20855.099051722835, 20901.79098209029, 7364.2635736427, 7433.591152486665, 7473.148952002976, 7593.164751877289, 7674.462787604925, 4206.432131662186, 4225.936610950838, 4234.978825105854, 4244.511881133709, 4350.60844523988, 3515.8066306352885, 3638.6179451157777, 3652.0139562318027, 3653.1415117447377, 3658.4695219193068, 660.1220376898713, 664.8902931016328, 672.3914857054374, 676.3439463720183, 679.3376457953456, 599.6807024546016, 615.9384061593805, 622.8318354044383, 626.7806267806322, 646.6443456099126, 8421.242088211968, 8874.023857375683, 8934.50860202695, 8946.731598052069, 8985.775355616162, 578.7540295374449, 591.4617697894895, 609.8536351275662, 633.1262857256955, 637.6461064109371, 4168.708190426641, 4350.73895566267, 4366.737995720267, 4393.592677345576, 4503.234450143484, 90721.54639226894, 92887.24262555718, 146715.2664236672, 147426.0, 156711.0, 127744.0, 129845.35077324553, 131028.0, 132334.99999999808, 134199.3290033563, 111886.44056779664, 116211.0, 116778.24832627518, 118257.0, 119269.0, 8183.3453323732765, 8450.746477605682, 8461.788455288666, 8620.439671421338, 8658.48049117055, 56269.71865140648, 58676.70661646665, 60484.69757651183, 60835.0, 63116.68441657762, 78554.32527681226, 84755.30489390054, 88122.55938720265, 92184.0, 97097.51451242699, 12658.063420215087, 13038.282894440517, 13479.19124852512, 13670.11144275619, 13995.300234988414, 13188.538401155913, 14371.0, 14429.5770434921, 14878.404863805326, 15101.924490377478, 13332.93333533326, 13687.452501899812, 13709.97478648246, 13819.239941803296, 14376.133360642376, 13341.866581334063, 13634.727305454035, 13801.309934503435, 13931.651708707363, 14144.564223278177, 13281.734365312834, 13434.261115638734, 13533.458661653423, 13562.457501699822, 13799.310034498034, 10332.0, 10661.946690266499, 10973.451327433437, 11066.9195984128, 11221.382823944436, 11222.607208747655, 11246.83129753038, 11337.489812958273, 11453.541858325574, 11486.425678716198], "value": 7784.339777169774, "unit": "score"}], "name": "dromaeo_css", "value": 7235.064714358767, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 162.0, 161.0, 113.0, 113.0, 113.0, 111.0, 110.0, 113.0, 112.0], "value": 122.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 112.0, 114.0, 151.0, 117.0, 132.0, 134.0, 115.0, 144.0, 113.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 186.0, 185.0, 187.0, 186.0, 184.0, 186.0, 180.0, 184.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 89.0, 60.0, 59.0, 59.0, 59.0, 84.0, 58.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 93.0, 70.0, 69.0, 69.0, 94.0, 68.0, 70.0, 91.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 83.0, 84.0, 95.0, 86.0, 84.0, 86.0, 82.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 97.0, 96.0, 98.0, 96.0], "value": 96.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 80.0, 80.0, 80.0, 81.0, 84.0, 87.0, 65.0, 94.0], "value": 81.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 53.0, 53.0, 53.0, 54.0, 54.0, 55.0, 53.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 77.0, 79.0, 74.0, 71.0, 70.0, 69.0, 71.0, 69.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 87.0, 89.0, 88.0, 85.0, 90.0, 86.0, 87.0, 103.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 147.0, 148.0, 150.0, 151.0, 135.0, 151.0, 163.0, 187.0, 159.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 176.0, 176.0, 175.0, 175.0, 180.0, 220.0, 218.0, 165.0, 227.0], "value": 188.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 86.0, 91.0, 84.0, 84.0, 77.0, 68.0, 69.0, 68.0], "value": 75.8, "unit": "score"}], "name": "kraken", "value": 1476.5, "alertThreshold": 2.0}]} 17:36:56 INFO - Return code: 0 17:36:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:36:56 INFO - # TBPL SUCCESS # 17:36:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:36:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:36:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:36:56 INFO - 2016-10-25 17:36:56.920 screenresolution[2794:44362] starting screenresolution argv=screenresolution get 17:36:56 INFO - 2016-10-25 17:36:56.925 screenresolution[2794:44362] Display 0: 1600x1200x32@60 17:36:56 INFO - 2016-10-25 17:36:56.931 screenresolution[2795:44365] starting screenresolution argv=screenresolution list 17:36:56 INFO - Available Modes on Display 0 17:36:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:36:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:36:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:36:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:36:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:36:57 INFO - Intel Iris: 17:36:57 INFO - Chipset Model: Intel Iris 17:36:57 INFO - Type: GPU 17:36:57 INFO - Bus: Built-In 17:36:57 INFO - VRAM (Dynamic, Max): 1536 MB 17:36:57 INFO - Vendor: Intel (0x8086) 17:36:57 INFO - Device ID: 0x0a2e 17:36:57 INFO - Revision ID: 0x0009 17:36:57 INFO - Displays: 17:36:57 INFO - Display: 17:36:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:36:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:36:57 INFO - Display Serial Number: 1600x1200 60 17:36:57 INFO - Main Display: Yes 17:36:57 INFO - Mirror: Off 17:36:57 INFO - Online: Yes 17:36:57 INFO - Rotation: Supported 17:36:57 INFO - Return code: 0 17:36:57 INFO - Running post-action listener: _resource_record_post_action 17:36:57 INFO - [mozharness: 2016-10-26 00:36:57.181357Z] Finished run-tests step (success) 17:36:57 INFO - Running post-run listener: _resource_record_post_run 17:36:57 INFO - Total resource usage - Wall time: 1216s; CPU: 54.0%; Read bytes: 106496; Write bytes: 706705408; Read time: 4; Write time: 2632 17:36:57 INFO - TinderboxPrint: CPU usage
53.5% 17:36:57 INFO - TinderboxPrint: I/O read bytes / time
106,496 / 4 17:36:57 INFO - TinderboxPrint: I/O write bytes / time
706,705,408 / 2,632 17:36:57 INFO - TinderboxPrint: CPU idle
2,260.8 (46.5%) 17:36:57 INFO - TinderboxPrint: CPU system
971.0 (20.0%) 17:36:57 INFO - TinderboxPrint: CPU user
1,633.9 (33.6%) 17:36:57 INFO - TinderboxPrint: Swap in / out
404,238,336 / 0 17:36:57 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 382849536; Write bytes: 376956416; Read time: 29033; Write time: 669 17:36:57 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 106496; Write bytes: 299500032; Read time: 4; Write time: 1904 17:36:57 INFO - Running post-run listener: _upload_blobber_files 17:36:57 INFO - Blob upload gear active. 17:36:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:36:57 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 17:36:57 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:36:57 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:36:58 INFO - (blobuploader) - INFO - Open directory for files ... 17:36:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:36:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:36:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:58 INFO - (blobuploader) - INFO - Done attempting. 17:36:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:36:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:36:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:59 INFO - (blobuploader) - INFO - Done attempting. 17:36:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:36:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:37:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:02 INFO - (blobuploader) - INFO - Done attempting. 17:37:02 INFO - (blobuploader) - INFO - Iteration through files over. 17:37:02 INFO - Return code: 0 17:37:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:37:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:37:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2f19d10e62646c0587cac4b95626a9ac7a96b2047d08ab1795049029936740a50aabd633480781e3f67d42989774ed2adfd0c4748c3e7b2499359ff1a2a76b79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/148f40cb64ff573807883d3f4362fdd37d9de971cb5ab0b25d9c478a673cc04ff81b7b3e047883e2956141722c73c0e7a3674d2430d549841353820bf778acaa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9a082d1d5fdcca6aab9a71f2de1941cf7e7e0894a603e309dd60639788b0de7d8ee5d0dbc80079b817ef70aaedd8f9f6ab42e8fe1e3c71d236cdb988bd8586f4"} 17:37:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:37:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:37:02 INFO - Contents: 17:37:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2f19d10e62646c0587cac4b95626a9ac7a96b2047d08ab1795049029936740a50aabd633480781e3f67d42989774ed2adfd0c4748c3e7b2499359ff1a2a76b79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/148f40cb64ff573807883d3f4362fdd37d9de971cb5ab0b25d9c478a673cc04ff81b7b3e047883e2956141722c73c0e7a3674d2430d549841353820bf778acaa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9a082d1d5fdcca6aab9a71f2de1941cf7e7e0894a603e309dd60639788b0de7d8ee5d0dbc80079b817ef70aaedd8f9f6ab42e8fe1e3c71d236cdb988bd8586f4"} 17:37:02 INFO - Running post-run listener: copy_logs_to_upload_dir 17:37:02 INFO - Copying logs to upload dir... 17:37:02 INFO - mkdir: /builds/slave/test/build/upload/logs 17:37:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.628852 ========= master_lag: 0.72 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-25 17:37:03.502996) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 17:37:03.506863) ========= 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.e5exXDl5Gj/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.7A1rZR9QZC/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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/Larch/sha512/2f19d10e62646c0587cac4b95626a9ac7a96b2047d08ab1795049029936740a50aabd633480781e3f67d42989774ed2adfd0c4748c3e7b2499359ff1a2a76b79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/148f40cb64ff573807883d3f4362fdd37d9de971cb5ab0b25d9c478a673cc04ff81b7b3e047883e2956141722c73c0e7a3674d2430d549841353820bf778acaa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9a082d1d5fdcca6aab9a71f2de1941cf7e7e0894a603e309dd60639788b0de7d8ee5d0dbc80079b817ef70aaedd8f9f6ab42e8fe1e3c71d236cdb988bd8586f4"} build_url:https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011272 build_url: 'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2f19d10e62646c0587cac4b95626a9ac7a96b2047d08ab1795049029936740a50aabd633480781e3f67d42989774ed2adfd0c4748c3e7b2499359ff1a2a76b79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/148f40cb64ff573807883d3f4362fdd37d9de971cb5ab0b25d9c478a673cc04ff81b7b3e047883e2956141722c73c0e7a3674d2430d549841353820bf778acaa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9a082d1d5fdcca6aab9a71f2de1941cf7e7e0894a603e309dd60639788b0de7d8ee5d0dbc80079b817ef70aaedd8f9f6ab42e8fe1e3c71d236cdb988bd8586f4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 17:37:03.545236) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 17:37:03.545702) ========= 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.e5exXDl5Gj/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.7A1rZR9QZC/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.005408 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 17:37:03.595881) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 17:37:03.596252) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 17:37:03.596756) ========= ========= Total master_lag: 0.87 =========