builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0293 starttime: 1476718784.74 results: success (0) buildid: 20161017062549 builduid: a004b4fe3f8843e8a263956d331d23a1 revision: 7c8216f48c38a8498f251fe044509b930af44de6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 08:39:44.740926) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 08:39:44.741354) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 08:39:44.741621) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 08:39:44.765658) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 08:39:44.765979) ========= 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.oGr6JJNxuA/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.zMNVqBOSyU/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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-17 08:39:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 67.5M=0s 2016-10-17 08:39:44 (67.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133415 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 08:39:44.921652) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 08:39:44.922018) ========= 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.oGr6JJNxuA/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.zMNVqBOSyU/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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.024519 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 08:39:44.971276) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 08:39:44.971737) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7c8216f48c38a8498f251fe044509b930af44de6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 7c8216f48c38a8498f251fe044509b930af44de6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oGr6JJNxuA/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.zMNVqBOSyU/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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-17 08:39:45,050 truncating revision to first 12 chars 2016-10-17 08:39:45,050 Setting DEBUG logging. 2016-10-17 08:39:45,050 attempt 1/10 2016-10-17 08:39:45,050 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7c8216f48c38?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-17 08:39:45,798 unpacking tar archive at: mozilla-central-7c8216f48c38/testing/mozharness/ program finished with exit code 0 elapsedTime=1.014200 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 08:39:46.007945) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 08:39:46.008273) ========= script_repo_revision: 7c8216f48c38a8498f251fe044509b930af44de6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 08:39:46.008672) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 08:39:46.008932) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 08:39:46.541459) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-17 08:39:46.541848) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oGr6JJNxuA/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.zMNVqBOSyU/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 08:39:46 INFO - MultiFileLogger online at 20161017 08:39:46 in /builds/slave/test 08:39:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:39:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:39:46 INFO - {'append_to_log': False, 08:39:46 INFO - 'base_work_dir': '/builds/slave/test', 08:39:46 INFO - 'blob_upload_branch': 'Firefox', 08:39:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:39:46 INFO - 'branch': 'Firefox', 08:39:46 INFO - 'buildbot_json_path': 'buildprops.json', 08:39:46 INFO - 'config_files': ('talos/mac_config.py',), 08:39:46 INFO - 'default_actions': ('clobber', 08:39:46 INFO - 'read-buildbot-config', 08:39:46 INFO - 'download-and-extract', 08:39:46 INFO - 'populate-webroot', 08:39:46 INFO - 'create-virtualenv', 08:39:46 INFO - 'install', 08:39:46 INFO - 'run-tests'), 08:39:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:39:46 INFO - 'download_minidump_stackwalk': True, 08:39:46 INFO - 'download_symbols': 'ondemand', 08:39:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:39:46 INFO - 'tooltool.py': '/tools/tooltool.py', 08:39:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:39:46 INFO - '/tools/misc-python/virtualenv.py')}, 08:39:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:39:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:39:46 INFO - 'installer_path': 'installer.exe', 08:39:46 INFO - 'log_level': 'info', 08:39:46 INFO - 'log_name': 'talos', 08:39:46 INFO - 'log_to_console': True, 08:39:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:39:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:39:46 INFO - 'opt_config_files': (), 08:39:46 INFO - 'pip_index': False, 08:39:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:39:46 INFO - 'cmd': ('bash', 08:39:46 INFO - '-c', 08:39:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:39:46 INFO - 'enabled': True, 08:39:46 INFO - 'halt_on_failure': False, 08:39:46 INFO - 'name': 'check_screen_resolution'},), 08:39:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:39:46 INFO - 'cmd': ('bash', 08:39:46 INFO - '-c', 08:39:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:39:46 INFO - 'enabled': True, 08:39:46 INFO - 'halt_on_failure': False, 08:39:46 INFO - 'name': 'check_screen_resolution'},), 08:39:46 INFO - 'run_cmd_checks_enabled': True, 08:39:46 INFO - 'sps_profile': False, 08:39:46 INFO - 'sps_profile_interval': 0, 08:39:46 INFO - 'suite': 'dromaeojs', 08:39:46 INFO - 'system_bits': '32', 08:39:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:39:46 INFO - 'title': 't-yosemite-r7-0293', 08:39:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:39:46 INFO - 'use_talos_json': True, 08:39:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:39:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:39:46 INFO - 'work_dir': 'build'} 08:39:46 INFO - [mozharness: 2016-10-17 15:39:46.742103Z] Running clobber step. 08:39:46 INFO - Running pre-action listener: _resource_record_pre_action 08:39:46 INFO - Running main action method: clobber 08:39:46 INFO - rmtree: /builds/slave/test/build 08:39:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:39:48 INFO - Running post-action listener: _resource_record_post_action 08:39:48 INFO - [mozharness: 2016-10-17 15:39:48.878203Z] Finished clobber step (success) 08:39:48 INFO - [mozharness: 2016-10-17 15:39:48.878341Z] Running read-buildbot-config step. 08:39:48 INFO - Running pre-action listener: _resource_record_pre_action 08:39:48 INFO - Running main action method: read_buildbot_config 08:39:48 INFO - Using buildbot properties: 08:39:48 INFO - { 08:39:48 INFO - "project": "", 08:39:48 INFO - "product": "firefox", 08:39:48 INFO - "script_repo_revision": "production", 08:39:48 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:39:48 INFO - "repository": "", 08:39:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:39:48 INFO - "buildid": "20161017062549", 08:39:48 INFO - "pgo_build": "False", 08:39:48 INFO - "basedir": "/builds/slave/test", 08:39:48 INFO - "buildnumber": 50, 08:39:48 INFO - "slavename": "t-yosemite-r7-0293", 08:39:48 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 08:39:48 INFO - "platform": "yosemite_r7", 08:39:48 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:39:48 INFO - "branch": "mozilla-central", 08:39:48 INFO - "repo_path": "mozilla-central", 08:39:48 INFO - "revision": "7c8216f48c38a8498f251fe044509b930af44de6", 08:39:48 INFO - "stage_platform": "macosx64", 08:39:48 INFO - "builduid": "a004b4fe3f8843e8a263956d331d23a1", 08:39:48 INFO - "slavebuilddir": "test" 08:39:48 INFO - } 08:39:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:39:48 INFO - Running post-action listener: _resource_record_post_action 08:39:48 INFO - [mozharness: 2016-10-17 15:39:48.881547Z] Finished read-buildbot-config step (success) 08:39:48 INFO - [mozharness: 2016-10-17 15:39:48.881669Z] Running download-and-extract step. 08:39:48 INFO - Running pre-action listener: _resource_record_pre_action 08:39:48 INFO - Running main action method: download_and_extract 08:39:48 INFO - mkdir: /builds/slave/test/build/tests 08:39:48 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')]} 08:39:48 INFO - trying https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:39:48 INFO - Downloading https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/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 08:39:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/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 08:39:49 INFO - Downloaded 1199 bytes. 08:39:49 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:39:49 INFO - Using the following test package requirements: 08:39:49 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:39:49 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:39:49 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:39:49 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:39:49 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:39:49 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:39:49 INFO - u'jsshell-mac.zip'], 08:39:49 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:39:49 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:39:49 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:39:49 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:39:49 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:39:49 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:39:49 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:39:49 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:39:49 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:39:49 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:39:49 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:39:49 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:39:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:39:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:39:49 INFO - Fetch https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:39:50 INFO - Http code: 200 08:39:50 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 08:39:50 INFO - x-amz-cf-id: 0dRvva1Yi3rR2_pixnSvrrSg5Y49CYzkPKHy81xRsqaybOUdN1F-eQ== 08:39:50 INFO - x-amz-version-id: zCM0TBfp8lc7PJbW791fwnwt_a07qFAp 08:39:50 INFO - Expected file size: 16482971 08:39:50 INFO - Obtained file size: 16482971 08:39:57 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 08:39:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:39:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:39:57 INFO - Fetch https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:39:57 INFO - Http code: 200 08:39:57 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 08:39:57 INFO - x-amz-cf-id: YWeGrdACiqSj4Mr9jV2tQ20dAAtwOXDlL_EPMK4B4dLr8q9ZTr-_IA== 08:39:57 INFO - x-amz-version-id: zCM0TBfp8lc7PJbW791fwnwt_a07qFAp 08:39:58 INFO - Expected file size: 16482971 08:39:58 INFO - Obtained file size: 16482971 08:40:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:40:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:40:04 INFO - Fetch https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:40:05 INFO - Http code: 200 08:40:05 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 08:40:05 INFO - x-amz-cf-id: oep_s7amtHbY9S_QJ8NDtwKyuqJLVSG7aVaRX-uwCmS-WnF87EDmsw== 08:40:05 INFO - x-amz-version-id: A4MGFwN.pKJqnpMdRDu65OvbUHdkpjpS 08:40:05 INFO - Expected file size: 14409028 08:40:05 INFO - Obtained file size: 14409028 08:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:05 INFO - trying https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:40:05 INFO - Downloading https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:40:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/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 08:40:08 INFO - Downloaded 111645794 bytes. 08:40:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:40:08 INFO - mkdir: /builds/slave/test/properties 08:40:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:40:08 INFO - Writing to file /builds/slave/test/properties/build_url 08:40:08 INFO - Contents: 08:40:08 INFO - build_url:https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:40:09 INFO - Running post-action listener: _resource_record_post_action 08:40:09 INFO - Running post-action listener: set_extra_try_arguments 08:40:09 INFO - [mozharness: 2016-10-17 15:40:09.451610Z] Finished download-and-extract step (success) 08:40:09 INFO - [mozharness: 2016-10-17 15:40:09.451744Z] Running populate-webroot step. 08:40:09 INFO - Running pre-action listener: _resource_record_pre_action 08:40:09 INFO - Running main action method: populate_webroot 08:40:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:40:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:40:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:40:09 INFO - '--tppagecycles', 08:40:09 INFO - '7'], 08:40:09 INFO - 'tests': ['tsvgm']}}, 08:40:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:40:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:40:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:40:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:40:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:40:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:40:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:09 INFO - 'talos_options': ['--disable-e10s'], 08:40:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:40:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:40:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:09 INFO - 'talos_options': ['--disable-e10s'], 08:40:09 INFO - 'tests': ['damp', 'tps']}, 08:40:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:09 INFO - 'tests': ['damp', 'tps']}, 08:40:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:40:09 INFO - 'tests': ['dromaeo_dom']}, 08:40:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:40:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:40:09 INFO - 'tests': ['basic_compositor_video']}, 08:40:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:40:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:40:09 INFO - 'tests': ['a11yr', 08:40:09 INFO - 'ts_paint', 08:40:09 INFO - 'tpaint', 08:40:09 INFO - 'sessionrestore', 08:40:09 INFO - 'sessionrestore_no_auto_restore', 08:40:09 INFO - 'tabpaint']}, 08:40:09 INFO - 'other-e10s': {'tests': ['a11yr', 08:40:09 INFO - 'ts_paint', 08:40:09 INFO - 'tpaint', 08:40:09 INFO - 'sessionrestore', 08:40:09 INFO - 'sessionrestore_no_auto_restore', 08:40:09 INFO - 'tabpaint']}, 08:40:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:40:09 INFO - 'ts_paint', 08:40:09 INFO - 'tpaint', 08:40:09 INFO - 'sessionrestore', 08:40:09 INFO - 'sessionrestore_no_auto_restore', 08:40:09 INFO - 'tabpaint']}, 08:40:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:40:09 INFO - 'ts_paint', 08:40:09 INFO - 'tpaint', 08:40:09 INFO - 'sessionrestore', 08:40:09 INFO - 'sessionrestore_no_auto_restore', 08:40:09 INFO - 'tabpaint']}, 08:40:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:40:09 INFO - 'tests': ['a11yr', 08:40:09 INFO - 'ts_paint', 08:40:09 INFO - 'tpaint', 08:40:09 INFO - 'sessionrestore', 08:40:09 INFO - 'sessionrestore_no_auto_restore', 08:40:09 INFO - 'tabpaint']}, 08:40:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:40:09 INFO - 'tests': ['a11yr', 08:40:09 INFO - 'ts_paint', 08:40:09 INFO - 'tpaint', 08:40:09 INFO - 'sessionrestore', 08:40:09 INFO - 'sessionrestore_no_auto_restore', 08:40:09 INFO - 'tabpaint']}, 08:40:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:40:09 INFO - 'tests': ['tsvgx', 08:40:09 INFO - 'tsvgr_opacity', 08:40:09 INFO - 'tart', 08:40:09 INFO - 'tscrollx', 08:40:09 INFO - 'cart']}, 08:40:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:40:09 INFO - 'tsvgr_opacity', 08:40:09 INFO - 'tart', 08:40:09 INFO - 'tscrollx', 08:40:09 INFO - 'cart']}, 08:40:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:09 INFO - 'talos_options': ['--disable-e10s'], 08:40:09 INFO - 'tests': ['tp5o']}, 08:40:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:09 INFO - 'tests': ['tp5o']}, 08:40:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:09 INFO - 'talos_options': ['--disable-e10s', 08:40:09 INFO - '--xperf_path', 08:40:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:40:09 INFO - 'tests': ['tp5n']}, 08:40:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:09 INFO - 'talos_options': ['--xperf_path', 08:40:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:40:09 INFO - 'tests': ['tp5n']}}, 08:40:09 INFO - 'talos.zip': {'path': '', 08:40:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:40:09 INFO - Running post-action listener: _resource_record_post_action 08:40:09 INFO - [mozharness: 2016-10-17 15:40:09.470085Z] Finished populate-webroot step (success) 08:40:09 INFO - [mozharness: 2016-10-17 15:40:09.470201Z] Running create-virtualenv step. 08:40:09 INFO - Running pre-action listener: _resource_record_pre_action 08:40:09 INFO - Running main action method: create_virtualenv 08:40:09 INFO - Creating virtualenv /builds/slave/test/build/venv 08:40:09 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 08:40:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:40:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:40:09 INFO - Using real prefix '/tools/python27' 08:40:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:40:10 INFO - Installing distribute.............................................................................................................................................................................................done. 08:40:13 INFO - Installing pip.................done. 08:40:13 INFO - Return code: 0 08:40:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:40:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:40:13 INFO - Reading from file tmpfile_stdout 08:40:13 INFO - Output received: 08:40:13 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) 08:40:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cb96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9632569bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aad30>, '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 0x10c7d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a2b20>, '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.oGr6JJNxuA/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.zMNVqBOSyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 08:40: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', 'pip>=1.5'] in /builds/slave/test/build 08:40: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 pip>=1.5 08:40:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oGr6JJNxuA/Render', 08:40:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:13 INFO - 'HOME': '/Users/cltbld', 08:40:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:13 INFO - 'LOGNAME': 'cltbld', 08:40:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:13 INFO - 'MOZ_NO_REMOTE': '1', 08:40:13 INFO - 'NO_EM_RESTART': '1', 08:40:13 INFO - 'PAGER': '/bin/cat', 08:40:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:13 INFO - 'PWD': '/builds/slave/test', 08:40:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:13 INFO - 'SHELL': '/bin/bash', 08:40:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMNVqBOSyU/Listeners', 08:40:13 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 08:40:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:13 INFO - 'USER': 'cltbld', 08:40:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:13 INFO - 'XPC_FLAGS': '0x0', 08:40:13 INFO - 'XPC_SERVICE_NAME': '0', 08:40:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40: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 08:40:13 INFO - Cleaning up... 08:40:13 INFO - Return code: 0 08:40:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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 0x10cb96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9632569bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aad30>, '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 0x10c7d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a2b20>, '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.oGr6JJNxuA/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.zMNVqBOSyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 08:40: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 08:40: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 08:40:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oGr6JJNxuA/Render', 08:40:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:13 INFO - 'HOME': '/Users/cltbld', 08:40:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:13 INFO - 'LOGNAME': 'cltbld', 08:40:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:13 INFO - 'MOZ_NO_REMOTE': '1', 08:40:13 INFO - 'NO_EM_RESTART': '1', 08:40:13 INFO - 'PAGER': '/bin/cat', 08:40:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:13 INFO - 'PWD': '/builds/slave/test', 08:40:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:13 INFO - 'SHELL': '/bin/bash', 08:40:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMNVqBOSyU/Listeners', 08:40:13 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 08:40:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:13 INFO - 'USER': 'cltbld', 08:40:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:13 INFO - 'XPC_FLAGS': '0x0', 08:40:13 INFO - 'XPC_SERVICE_NAME': '0', 08:40:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:14 INFO - Downloading/unpacking psutil>=3.1.1 08:40:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:40:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:40:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:40:17 INFO - Installing collected packages: psutil 08:40:17 INFO - Running setup.py install for psutil 08:40:17 INFO - building 'psutil._psutil_osx' extension 08:40: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 08:40:18 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 08:40:18 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 08:40:18 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 08:40:18 INFO - building 'psutil._psutil_posix' extension 08:40:18 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 08:40:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:40:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:40:18 INFO - ^ 08:40:18 INFO - 1 warning generated. 08:40:18 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 08:40:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:40:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:40:18 INFO - Successfully installed psutil 08:40:18 INFO - Cleaning up... 08:40:18 INFO - Return code: 0 08:40:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:40: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')]} 08:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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')]} 08:40:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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 0x10cb96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9632569bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aad30>, '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 0x10c7d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a2b20>, '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.oGr6JJNxuA/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.zMNVqBOSyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 08:40: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 08:40: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 08:40:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oGr6JJNxuA/Render', 08:40:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:18 INFO - 'HOME': '/Users/cltbld', 08:40:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:18 INFO - 'LOGNAME': 'cltbld', 08:40:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:18 INFO - 'MOZ_NO_REMOTE': '1', 08:40:18 INFO - 'NO_EM_RESTART': '1', 08:40:18 INFO - 'PAGER': '/bin/cat', 08:40:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:18 INFO - 'PWD': '/builds/slave/test', 08:40:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:18 INFO - 'SHELL': '/bin/bash', 08:40:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMNVqBOSyU/Listeners', 08:40:18 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 08:40:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:18 INFO - 'USER': 'cltbld', 08:40:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:18 INFO - 'XPC_FLAGS': '0x0', 08:40:18 INFO - 'XPC_SERVICE_NAME': '0', 08:40:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:40: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 08:40: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 08:40: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 08:40: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 08:40: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 08:40: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 08:40:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:40:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:40:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:40:21 INFO - Installing collected packages: mozsystemmonitor 08:40:21 INFO - Running setup.py install for mozsystemmonitor 08:40:21 INFO - Successfully installed mozsystemmonitor 08:40:21 INFO - Cleaning up... 08:40:21 INFO - Return code: 0 08:40:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:40: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')]} 08:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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')]} 08:40:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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 0x10cb96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9632569bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aad30>, '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 0x10c7d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a2b20>, '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.oGr6JJNxuA/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.zMNVqBOSyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 08:40: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 08:40: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 08:40:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oGr6JJNxuA/Render', 08:40:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:21 INFO - 'HOME': '/Users/cltbld', 08:40:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:21 INFO - 'LOGNAME': 'cltbld', 08:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:21 INFO - 'MOZ_NO_REMOTE': '1', 08:40:21 INFO - 'NO_EM_RESTART': '1', 08:40:21 INFO - 'PAGER': '/bin/cat', 08:40:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:21 INFO - 'PWD': '/builds/slave/test', 08:40:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:21 INFO - 'SHELL': '/bin/bash', 08:40:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMNVqBOSyU/Listeners', 08:40:21 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 08:40:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:21 INFO - 'USER': 'cltbld', 08:40:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:21 INFO - 'XPC_FLAGS': '0x0', 08:40:21 INFO - 'XPC_SERVICE_NAME': '0', 08:40:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:21 INFO - Downloading/unpacking jsonschema==2.5.1 08:40: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 08:40: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 08:40: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 08:40: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 08:40: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 08:40: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 08:40:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:40:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:24 INFO - Downloading functools32-3.2.3-2.tar.gz 08:40:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:40:24 INFO - warning: no files found matching '*.txt' 08:40:24 INFO - no previously-included directories found matching 'build' 08:40:24 INFO - no previously-included directories found matching 'dist' 08:40:24 INFO - no previously-included directories found matching '.git*' 08:40:24 INFO - Installing collected packages: jsonschema, functools32 08:40:25 INFO - Running setup.py install for functools32 08:40:25 INFO - warning: no files found matching '*.txt' 08:40:25 INFO - no previously-included directories found matching 'build' 08:40:25 INFO - no previously-included directories found matching 'dist' 08:40:25 INFO - no previously-included directories found matching '.git*' 08:40:25 INFO - Successfully installed jsonschema functools32 08:40:25 INFO - Cleaning up... 08:40:25 INFO - Return code: 0 08:40:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:40: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')]} 08:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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')]} 08:40:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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', '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 0x10cb96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9632569bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aad30>, '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 0x10c7d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a2b20>, '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.oGr6JJNxuA/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.zMNVqBOSyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 08:40: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:40: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 functools32==3.2.3-2 08:40:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oGr6JJNxuA/Render', 08:40:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:25 INFO - 'HOME': '/Users/cltbld', 08:40:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:25 INFO - 'LOGNAME': 'cltbld', 08:40:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:25 INFO - 'MOZ_NO_REMOTE': '1', 08:40:25 INFO - 'NO_EM_RESTART': '1', 08:40:25 INFO - 'PAGER': '/bin/cat', 08:40:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:25 INFO - 'PWD': '/builds/slave/test', 08:40:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:25 INFO - 'SHELL': '/bin/bash', 08:40:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMNVqBOSyU/Listeners', 08:40:25 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 08:40:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:25 INFO - 'USER': 'cltbld', 08:40:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:25 INFO - 'XPC_FLAGS': '0x0', 08:40:25 INFO - 'XPC_SERVICE_NAME': '0', 08:40:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:40:25 INFO - Cleaning up... 08:40:25 INFO - Return code: 0 08:40:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:40: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')]} 08:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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')]} 08:40:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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 0x10cb96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9632569bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aad30>, '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 0x10c7d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a2b20>, '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.oGr6JJNxuA/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.zMNVqBOSyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 08:40: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 08:40: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 08:40:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oGr6JJNxuA/Render', 08:40:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:25 INFO - 'HOME': '/Users/cltbld', 08:40:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:25 INFO - 'LOGNAME': 'cltbld', 08:40:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:25 INFO - 'MOZ_NO_REMOTE': '1', 08:40:25 INFO - 'NO_EM_RESTART': '1', 08:40:25 INFO - 'PAGER': '/bin/cat', 08:40:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:25 INFO - 'PWD': '/builds/slave/test', 08:40:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:25 INFO - 'SHELL': '/bin/bash', 08:40:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMNVqBOSyU/Listeners', 08:40:25 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 08:40:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:25 INFO - 'USER': 'cltbld', 08:40:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:25 INFO - 'XPC_FLAGS': '0x0', 08:40:25 INFO - 'XPC_SERVICE_NAME': '0', 08:40:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:25 INFO - Downloading/unpacking blobuploader==1.2.4 08:40: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 08:40: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 08:40: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 08:40: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 08:40: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 08:40: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 08:40:28 INFO - Downloading blobuploader-1.2.4.tar.gz 08:40:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:40:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:40: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 08:40: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 08:40: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 08:40: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 08:40: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 08:40: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 08:40:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:40:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:40: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 08:40: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 08:40: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 08:40: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 08:40: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 08:40: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 08:40:29 INFO - Downloading docopt-0.6.1.tar.gz 08:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:40:29 INFO - Installing collected packages: blobuploader, requests, docopt 08:40:29 INFO - Running setup.py install for blobuploader 08:40:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:40:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:40:29 INFO - Running setup.py install for requests 08:40:30 INFO - Running setup.py install for docopt 08:40:30 INFO - Successfully installed blobuploader requests docopt 08:40:30 INFO - Cleaning up... 08:40:30 INFO - Return code: 0 08:40:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:40:30 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')]} 08:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:30 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')]} 08:40:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:30 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 0x10cb96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9632569bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aad30>, '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 0x10c7d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a2b20>, '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.oGr6JJNxuA/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.zMNVqBOSyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 08:40:30 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 08:40:30 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 08:40:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oGr6JJNxuA/Render', 08:40:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:30 INFO - 'HOME': '/Users/cltbld', 08:40:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:30 INFO - 'LOGNAME': 'cltbld', 08:40:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:30 INFO - 'MOZ_NO_REMOTE': '1', 08:40:30 INFO - 'NO_EM_RESTART': '1', 08:40:30 INFO - 'PAGER': '/bin/cat', 08:40:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:30 INFO - 'PWD': '/builds/slave/test', 08:40:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:30 INFO - 'SHELL': '/bin/bash', 08:40:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMNVqBOSyU/Listeners', 08:40:30 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 08:40:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:30 INFO - 'USER': 'cltbld', 08:40:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:30 INFO - 'XPC_FLAGS': '0x0', 08:40:30 INFO - 'XPC_SERVICE_NAME': '0', 08:40:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:40:30 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-ZRAqJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:40:30 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-jzKySK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:40:30 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-axLjmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:40:30 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-THJP99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:40:31 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-wYzm5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:40:31 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-ETJ1sN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:40:31 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Y_JxXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:40:31 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-lFFvdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:40:31 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-7SUamS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:40:31 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-oVY06N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:40:31 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-HNpXXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:40:31 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-w8kAQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:40:31 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-d0FnPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:40:32 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Ax6sBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:40:32 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-V5T1zH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:40:32 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-iYMk64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:40:32 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Ufmn7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:40:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:40:32 INFO - Running setup.py install for manifestparser 08:40:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:40:32 INFO - Running setup.py install for mozcrash 08:40:32 INFO - Running setup.py install for mozdebug 08:40:32 INFO - Running setup.py install for mozdevice 08:40:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:40:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:40:33 INFO - Running setup.py install for mozfile 08:40:33 INFO - Running setup.py install for mozhttpd 08:40:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:40:33 INFO - Running setup.py install for mozinfo 08:40:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:40:33 INFO - Running setup.py install for mozInstall 08:40:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:40:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:40:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:40:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:40:33 INFO - Running setup.py install for mozleak 08:40:33 INFO - Running setup.py install for mozlog 08:40:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:40:33 INFO - Running setup.py install for moznetwork 08:40:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:40:34 INFO - Running setup.py install for mozprocess 08:40:34 INFO - Running setup.py install for mozprofile 08:40:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:40:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:40:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:40:34 INFO - Running setup.py install for mozrunner 08:40:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:40:34 INFO - Running setup.py install for mozscreenshot 08:40:34 INFO - Running setup.py install for moztest 08:40:34 INFO - Running setup.py install for mozversion 08:40:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:40:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:40:34 INFO - Cleaning up... 08:40:35 INFO - Return code: 0 08:40:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:40:35 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')]} 08:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:35 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')]} 08:40:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:35 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 0x10cb96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9632569bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aad30>, '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 0x10c7d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a2b20>, '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.oGr6JJNxuA/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.zMNVqBOSyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 08:40:35 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 08:40:35 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 08:40:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oGr6JJNxuA/Render', 08:40:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:35 INFO - 'HOME': '/Users/cltbld', 08:40:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:35 INFO - 'LOGNAME': 'cltbld', 08:40:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:35 INFO - 'MOZ_NO_REMOTE': '1', 08:40:35 INFO - 'NO_EM_RESTART': '1', 08:40:35 INFO - 'PAGER': '/bin/cat', 08:40:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:35 INFO - 'PWD': '/builds/slave/test', 08:40:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:35 INFO - 'SHELL': '/bin/bash', 08:40:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMNVqBOSyU/Listeners', 08:40:35 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 08:40:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:35 INFO - 'USER': 'cltbld', 08:40:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:35 INFO - 'XPC_FLAGS': '0x0', 08:40:35 INFO - 'XPC_SERVICE_NAME': '0', 08:40:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:40:35 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Pz3Dwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:40: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)) 08:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:40:35 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-zP16UQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:40: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)) 08:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:40:35 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-xDtxnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:40: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)) 08:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:40:35 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-IcZbeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:40: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)) 08:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:40:35 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-s5YuS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:40: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)) 08:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:40:35 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-0Pq67O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:40: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)) 08:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:40:35 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-buPfyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:40:36 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)) 08:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:40:36 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-CsLrEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:40:36 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)) 08:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:40:36 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-rk3EcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:40: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)) 08:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:40:36 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-FEdKlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:40: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)) 08:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:40:36 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-BwEAMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:40: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)) 08:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:40:36 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-PyXn0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:40: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)) 08:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:40:36 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-mMdemA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:40: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)) 08:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:40:36 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-aucR76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:40: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)) 08:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:40:36 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-u6gSvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:40: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)) 08:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:40:36 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-m7ZsOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:40: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)) 08:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:40:36 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-1xNjS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:40:37 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)) 08:40:37 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)) 08:40:37 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)) 08:40:37 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)) 08:40:37 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)) 08:40:37 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)) 08:40:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:39 INFO - Downloading blessings-1.6.tar.gz 08:40:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:40:39 INFO - Installing collected packages: blessings 08:40:39 INFO - Running setup.py install for blessings 08:40:39 INFO - Successfully installed blessings 08:40:39 INFO - Cleaning up... 08:40:39 INFO - Return code: 0 08:40:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:40:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:40:40 INFO - Reading from file tmpfile_stdout 08:40:40 INFO - Current package versions: 08:40:40 INFO - blessings == 1.6 08:40:40 INFO - blobuploader == 1.2.4 08:40:40 INFO - docopt == 0.6.1 08:40:40 INFO - functools32 == 3.2.3-2 08:40:40 INFO - jsonschema == 2.5.1 08:40:40 INFO - manifestparser == 1.1 08:40:40 INFO - mozInstall == 1.12 08:40:40 INFO - mozcrash == 0.17 08:40:40 INFO - mozdebug == 0.1 08:40:40 INFO - mozdevice == 0.48 08:40:40 INFO - mozfile == 1.2 08:40:40 INFO - mozhttpd == 0.7 08:40:40 INFO - mozinfo == 0.9 08:40:40 INFO - mozleak == 0.1 08:40:40 INFO - mozlog == 3.4 08:40:40 INFO - moznetwork == 0.27 08:40:40 INFO - mozprocess == 0.23 08:40:40 INFO - mozprofile == 0.28 08:40:40 INFO - mozrunner == 6.12 08:40:40 INFO - mozscreenshot == 0.1 08:40:40 INFO - mozsystemmonitor == 0.3 08:40:40 INFO - moztest == 0.7 08:40:40 INFO - mozversion == 1.4 08:40:40 INFO - psutil == 3.1.1 08:40:40 INFO - requests == 1.2.3 08:40:40 INFO - wsgiref == 0.1.2 08:40:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:40: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')]} 08:40:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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')]} 08:40:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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 0x10cb96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9632569bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aad30>, '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 0x10c7d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a2b20>, '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.oGr6JJNxuA/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.zMNVqBOSyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 08:40: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 08:40: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 08:40:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oGr6JJNxuA/Render', 08:40:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:40 INFO - 'HOME': '/Users/cltbld', 08:40:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:40 INFO - 'LOGNAME': 'cltbld', 08:40:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:40 INFO - 'MOZ_NO_REMOTE': '1', 08:40:40 INFO - 'NO_EM_RESTART': '1', 08:40:40 INFO - 'PAGER': '/bin/cat', 08:40:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:40 INFO - 'PWD': '/builds/slave/test', 08:40:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:40 INFO - 'SHELL': '/bin/bash', 08:40:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMNVqBOSyU/Listeners', 08:40:40 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 08:40:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:40 INFO - 'USER': 'cltbld', 08:40:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:40 INFO - 'XPC_FLAGS': '0x0', 08:40:40 INFO - 'XPC_SERVICE_NAME': '0', 08:40:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40: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)) 08:40: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)) 08:40: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)) 08:40: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)) 08:40: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)) 08:40: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)) 08:40: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)) 08:40: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)) 08:40: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)) 08:40: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)) 08:40: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)) 08:40:40 INFO - Cleaning up... 08:40:40 INFO - Return code: 0 08:40:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:40: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')]} 08:40:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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')]} 08:40:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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 0x10cb96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9632569bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aad30>, '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 0x10c7d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a2b20>, '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.oGr6JJNxuA/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.zMNVqBOSyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 08:40: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 08:40: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 08:40:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oGr6JJNxuA/Render', 08:40:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:40 INFO - 'HOME': '/Users/cltbld', 08:40:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:40 INFO - 'LOGNAME': 'cltbld', 08:40:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:40 INFO - 'MOZ_NO_REMOTE': '1', 08:40:40 INFO - 'NO_EM_RESTART': '1', 08:40:40 INFO - 'PAGER': '/bin/cat', 08:40:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:40 INFO - 'PWD': '/builds/slave/test', 08:40:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:40 INFO - 'SHELL': '/bin/bash', 08:40:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMNVqBOSyU/Listeners', 08:40:40 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 08:40:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:40 INFO - 'USER': 'cltbld', 08:40:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:40 INFO - 'XPC_FLAGS': '0x0', 08:40:40 INFO - 'XPC_SERVICE_NAME': '0', 08:40:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:40:40 INFO - Cleaning up... 08:40:40 INFO - Return code: 0 08:40:40 INFO - Running post-action listener: _resource_record_post_action 08:40:40 INFO - Running post-action listener: _start_resource_monitoring 08:40:40 INFO - Starting resource monitoring. 08:40:40 INFO - [mozharness: 2016-10-17 15:40:40.753227Z] Finished create-virtualenv step (success) 08:40:40 INFO - [mozharness: 2016-10-17 15:40:40.754275Z] Running install step. 08:40:40 INFO - Running pre-action listener: _resource_record_pre_action 08:40:40 INFO - Running main action method: install 08:40:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:40:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:40:41 INFO - Reading from file tmpfile_stdout 08:40:41 INFO - Detecting whether we're running mozinstall >=1.0... 08:40:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:40:41 INFO - Reading from file tmpfile_stdout 08:40:41 INFO - Output received: 08:40:41 INFO - Usage: mozinstall [options] installer 08:40:41 INFO - Options: 08:40:41 INFO - -h, --help show this help message and exit 08:40:41 INFO - -d DEST, --destination=DEST 08:40:41 INFO - Directory to install application into. [default: 08:40:41 INFO - "/builds/slave/test"] 08:40:41 INFO - --app=APP Application being installed. [default: firefox] 08:40:41 INFO - mkdir: /builds/slave/test/build/application 08:40: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'] 08:40: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 08:41:15 INFO - Reading from file tmpfile_stdout 08:41:15 INFO - Output received: 08:41:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:41:15 INFO - Running post-action listener: _resource_record_post_action 08:41:15 INFO - [mozharness: 2016-10-17 15:41:15.513208Z] Finished install step (success) 08:41:15 INFO - [mozharness: 2016-10-17 15:41:15.513341Z] Running run-tests step. 08:41:15 INFO - Running pre-action listener: _resource_record_pre_action 08:41:15 INFO - Running main action method: run_tests 08:41:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:41:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:41:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:41:15 INFO - 2016-10-17 08:41:15.528 screenresolution[1638:7383] starting screenresolution argv=screenresolution get 08:41:15 INFO - 2016-10-17 08:41:15.530 screenresolution[1638:7383] Display 0: 1600x1200x32@60 08:41:15 INFO - 2016-10-17 08:41:15.536 screenresolution[1639:7385] starting screenresolution argv=screenresolution list 08:41:15 INFO - Available Modes on Display 0 08:41:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:41:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:41:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:41:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:41:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:41:15 INFO - Intel Iris: 08:41:15 INFO - Chipset Model: Intel Iris 08:41:15 INFO - Type: GPU 08:41:15 INFO - Bus: Built-In 08:41:15 INFO - VRAM (Dynamic, Max): 1536 MB 08:41:15 INFO - Vendor: Intel (0x8086) 08:41:15 INFO - Device ID: 0x0a2e 08:41:15 INFO - Revision ID: 0x0009 08:41:15 INFO - Displays: 08:41:15 INFO - Display: 08:41:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:41:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:41:15 INFO - Display Serial Number: 1600x1200 60 08:41:15 INFO - Main Display: Yes 08:41:15 INFO - Mirror: Off 08:41:15 INFO - Online: Yes 08:41:15 INFO - Rotation: Supported 08:41:15 INFO - Return code: 0 08:41:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:41:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:41:15 INFO - Python 2.7.3 08:41:15 INFO - Return code: 0 08:41:15 INFO - grabbing minidump binary from tooltool 08:41: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')]} 08:41: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 0x10c9aad30>, '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 0x10c7d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:41: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 08:41: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 08:41: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 08:41:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:41:15 INFO - Return code: 0 08:41:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:41:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:41:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:41:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:41:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:41:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0293', '--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 08:41:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0293 --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 08:41:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oGr6JJNxuA/Render', 08:41:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:15 INFO - 'HOME': '/Users/cltbld', 08:41:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:15 INFO - 'LOGNAME': 'cltbld', 08:41:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:41:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:41:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:15 INFO - 'MOZ_NO_REMOTE': '1', 08:41:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:41:15 INFO - 'NO_EM_RESTART': '1', 08:41:15 INFO - 'PAGER': '/bin/cat', 08:41:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:15 INFO - 'PWD': '/builds/slave/test', 08:41:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:41:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:15 INFO - 'SHELL': '/bin/bash', 08:41:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMNVqBOSyU/Listeners', 08:41:15 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 08:41:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:15 INFO - 'USER': 'cltbld', 08:41:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:15 INFO - 'XPC_FLAGS': '0x0', 08:41:15 INFO - 'XPC_SERVICE_NAME': '0', 08:41:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0293', '--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 08:41:16 INFO - mozversion application_buildid: 20161017062549 08:41:16 INFO - mozversion application_changeset: 7c8216f48c38a8498f251fe044509b930af44de6 08:41:16 INFO - mozversion application_display_name: Nightly 08:41:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:41:16 INFO - mozversion application_name: Firefox 08:41:16 INFO - mozversion application_remotingname: firefox 08:41:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:41:16 INFO - mozversion application_vendor: Mozilla 08:41:16 INFO - mozversion application_version: 52.0a1 08:41:16 INFO - mozversion platform_buildid: 20161017062549 08:41:16 INFO - mozversion platform_changeset: 7c8216f48c38a8498f251fe044509b930af44de6 08:41:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:41:16 INFO - mozversion platform_version: 52.0a1 08:41:16 INFO - using testdate: 1476718876 08:41:16 INFO - actual date: 1476718876 08:41:16 INFO - starting webserver on 'localhost:49273' 08:41:16 INFO - SUITE-START | Running 2 tests 08:41:16 INFO - TEST-START | dromaeo_css 08:41:16 INFO - operating with platform_type : mac_ 08:41:16 INFO - Initialising browser for dromaeo_css test... 08:41:16 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpdMErXf/profile http://localhost:49273/getInfo.html) 08:41:18 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 08:41:18 INFO - PROCESS | 1649 | colorDepth:24 08:41:18 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 08:41:18 INFO - PROCESS | 1649 | __metrics 08:41:18 INFO - PROCESS | 1649 | 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" 08:41:18 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:41:19 INFO - TEST-INFO | 1649: exit 0 08:41:19 INFO - Browser initialized. 08:41:19 INFO - Running cycle 1/1 for dromaeo_css test... 08:41:19 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpdMErXf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:41:31 INFO - PROCESS | 1651 | RSS: Main: 239190016 08:41:31 INFO - PROCESS | 1651 | 08:42:22 INFO - PROCESS | 1651 | 1476718942584 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] 08:44:36 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 08:44:39 INFO - PROCESS | 1651 | RSS: Main: 435957760 08:44:39 INFO - PROCESS | 1651 | 08:47:41 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 08:47:42 INFO - PROCESS | 1651 | RSS: Main: 372154368 08:47:42 INFO - PROCESS | 1651 | 08:50:44 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 08:50:45 INFO - PROCESS | 1651 | RSS: Main: 343011328 08:50:45 INFO - PROCESS | 1651 | 08:53:47 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 08:53:48 INFO - PROCESS | 1651 | RSS: Main: 335699968 08:53:48 INFO - PROCESS | 1651 | 08:56:51 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 08:56:52 INFO - PROCESS | 1651 | RSS: Main: 334626816 08:56:52 INFO - PROCESS | 1651 | 08:59:55 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 08:59:56 INFO - PROCESS | 1651 | RSS: Main: 479682560 08:59:56 INFO - PROCESS | 1651 | 08:59:56 INFO - PROCESS | 1651 | __start_tp_report 08:59:56 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 08:59:56 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 08:59:56 INFO - PROCESS | 1651 | |i|pagename|runs| 08:59:56 INFO - PROCESS | 1651 | |0;dojo.html;3197.872085116595;3341.3985482613134;3351.2962277921642;3376.9362650764992;3396.7622266441344;5534.889302213964;5638.40796716345;5670.574706896979;5689.630174038684;5786;5639.182318563806;5685.744141513632;5695.316562012565;5695.45893140152;5706.457886500786;5115.616328775347;5168.534831865131;5173;5189.455107213745;5211.088059589576;5205.115130427836;5213.791448342062;5293.126634105368;5294.073537131006;5331.866703332409;5277.947220527803;5292.55013323866;5303.151495760679;5310.8141215057485;5354.063038968185;5302.019126461598;5307.575393968493;5311.389190243133;5333.786648534054;5338.866528336784;4935.9753201234125;5012.8997420051755;5174.146265866128;5182.818601348972;5186.170212765977;5586.1062230043;5587.329520457572;5623.493885550321;5629.127485239795;5642.984262832689;6168.982117950578;6169.259688837368;6256.937430625681;6260.029695397221;6272.749090036393;6211.534134939852;6261.937380626181;6269.905951410732;6281.623102613812;6335.936640633627;5809.302883653946;5823.184754134425;5834.687195381048;5834.824955251349;5887.470127688529;5103.642745007888;6222.968885155546;6300.558960872787;6315.842103947346;6316.147320111818;4738.1708201064675;5411.729413529308;5809.651420914674;5821.970890145522;5878.2064421302575;5568.916466253009;5930.406959304121;5966;5968.46283834457;5996.940030599638;5850.970745146247;5852.736626851802;6001.849953751192;6063.727132279058;6066.636001839904;6112.047575716847;6125.2659746757545;6125.540584456095;6139.185504090456;6139.960719443904;4883.194272944996;4892.0705066037335;4892.119418504632;4919.311296418504;4938.827141050046;4403.647708183337;4441.067375851043;4441.356003379492;4474.0604473060375;4491.1915855145735;4849.733264670477;5739.053056245672;5753.252077229933;5754.482096611325;5778.075507918713;6091.299500557493;6111.865232675556;6126.792505495304;6206.565540412128;6295.527835412271;5668.206451096849;5674.517665998354;5675.091985282335;5683.857903552444;5684.971575142098;5309.522143007071;5354.089804733205;5363.517283444508;5366.946330536723;5373.811916582901;6282.968585157045;6299.937000629935;6303.432691057827;6305.211848518935;6316.05259211122;6176.7838125665385;6203.2556093269095;6220.595661282002;6232.781852635136;6292.370762923671;6270.216222972129;6287.685615719287;6295;6298.842528936813;6345.904811427925;6103.816885493442;6134.8159555213415;6149.877002460015;6152.692365381802;6159.076138579249;5458.890822183614;6029.366916473882;6080.817575472743;6116.0010150213275;6143.938560614337;6106.794380079809;6120.112583675431;6142.498775697809;6147.047207682816;6150.2927163375;6013.428724271187;6131.552947516549;6145.832291864532;6146.6170111724505;6160.353162917829;5715.657060576379;5878.706064696834;5987.670678112746;5989.191459153133;6000.819975400745;4461.286194208911;5879.265091863517;5978;6031.698415079316;6083.1483592298;5025.824096156617;5869.090291004901;5872.324682661378;5885.323187833454;5905.91141132892 08:59:56 INFO - PROCESS | 1651 | |1;ext.html;488.5334505547203;510.8518529626408;523.5915985531287;530.9654872433294;550.8237364043505;26801.865990670045;41060.58939410605;41898.162036759284;42255;44201.33697994533;42919.35620965688;43486.13027739451;43523;43559.12881742363;43676.56323436765;9463.858042129355;9711.660091896785;9715;9715.757106072335;9727.270454715925;9561.28290378221;9673.516324183762;9680.273979451535;9680.370775899579;9695.078967498077;9669.12977831995;9711.465869377182;9720.027997200259;9729.805403891918;9769.316147869651;9594.616215351378;9665.130138287588;9673.081057299545;9684.661036863676;9700.514974251259;9570;9643.98738132493;9654;9654.082862128087;9658.806823863553;41174.38238426396;41528;41575;41751.58248417538;41788;3417.4019546579248;3440.8107554084513;3442.208292092833;3496.2133519957806;3894.4547763313158;30716.38567228665;31039.844800776078;31701.20746981321;31822.681773182205;32076.518981068675;3132.268414971001;3241.3679332530164;3247.285597168619;3253.577034985437;3263.184203949013;346656.8670096535;346769;347593;349415;357631;62841.68579157166;63253;63260.683696581225;63285.68357158277;63501;34398.48402273968;34816.82591587026;35338;65390.67304663541;65779.01331480032;16485.752713709306;16896.662066758596;16905.98564086159;16919.407820726214;16950.49148525546;12868.678283042811;12933.288669123289;12943.158694684815;12957.805632915513;12979.935100324627;19517.121729522205;19688.114034868464;19781.505462363555;19793.30723424673;19802.90098549498;7730.806729831799;7773;7896.526208427513;7900.762977110696;7992.280694737501;799.3884678221166;854.6837670062117;967.9739476155298;1014.5688082564881;1094.195766111907;29080.418391632047;29080.7091929078;30320;30320.696793032228;30862.691373086433;913.5386629751932;1075.3547871277328;1091.9290246133976;1110.6279396402135;1112.8330750387506;15160.772588411182;15464.922675386551;15697.529074127795;15793.921030394775;16150.857914973607;657557;661056.7573377629;663189;666145;670226;60369;60664.39335606676;61105.388946110856;61124.38875611187;61311.46721332002;1355.2275203134316;1380.9792853107213;1407.183833376639;1410.1398147130037;1411.908225965309;29817.552736708534;29875.55186672158;30123.849380752956;30227.109586855713;30350.544741829333;52502.737486312326;52778.472215277354;52806.735966319924;52905.47094529006;52996.735016324674;553011;558586.5201457922;563617;566892;568302;26293.34266643349;26338.341541461617;26339.736602634493;26834.59748103741;27229.59155612707;45818;47419;48349.516504835905;49059.50940490692;49218;33208.003759887244;39116.804415977735;39608.2078358437;40098.99752506211;40628.39057414082;39262.60737392589;40075.79962100171;40349.59650403458;40468.39297410482;40965;30566;33373.83313083516;33694.49458258177;39072;39996;31627;32184.35631287408;35029;35084.473732893515;35123.94628161159;21651.566968660223;24486.653234071586;24970.62544061804;25801.483970320864;25873.353166170997 08:59:56 INFO - PROCESS | 1651 | |2;jquery.html;346.3384934774578;358.4802037046282;366.7799320407755;371.9497867787849;393.77358019139;14662.340194691238;15054.322555485001;15390.230488475574;19454.70817937732;19826.801731982712;19621.31325403611;20485.795142048573;21048.36854894352;21100.472488187803;21299.25452609159;5812.999999999989;6122.755089796403;6129.264488261392;6139.969300153493;6142.815715528519;5018;6597.406233438989;6658.866822663544;6673.032410300503;6706.832329191785;5721.056025755745;6009.09863520471;6697.263301036882;6697.866042679144;6706.966465167668;6082.330943596201;6107.328193898667;6129.356417576179;6133.110698948627;6136.202293701813;5731.226284451587;5818.621789583706;5837.211976383176;5911.586188966774;5924.940750592482;13214.537491187762;13386.732265354736;13627.250501222428;21282.46793830151;23761.287161385186;2226.4322597737473;2304.9654255186188;2362.55111528809;2385.8449200802065;2905.4274930905763;9556.75762150916;9787.070228328297;9999.75000624983;10269.229807764448;10464.42445665488;1776.422662634649;1827.839322030507;1876.8873867567854;1894.4979580411064;1901.5531350132708;97020;100546.9945300545;110690.44654776755;119874;121070.78929210771;11379.886201138048;11397.487113079931;11524.711882202844;11592;12068.396580171131;14582.708345833023;14583.92708036453;14588.051776634486;14598.489052883311;14633.780493292608;6770.255271920085;8584.613692383859;8625.309975201968;8627.6980305689;8747;8590.785230369165;8596;8615.6984505542;8791.692290769792;8802.955985220033;4412.536683648235;4513.72917624937;4527.728336299832;4573.611243044312;4592.288195329729;1718.2869109319493;1829.9451016469527;1889.8299153076289;1889.9510771521661;1898.6202759448165;735.5292612727858;737.5464089584867;790.8853216283721;790.8892755014302;999.165696643311;18186.81785683926;19687.507812304808;23035.07859685594;23272.65091023636;23680.881595592255;816.4121832280748;881.3962435731545;946.9299691348732;954.8663187153804;956.32101208143;10805.621803236849;11530.656109728085;11611.129165312635;11661.88338116625;11684.357360345262;592416;593921;599547.0022649947;616855;618406;105594.4720276409;127617;134252;134716.32641836925;135328;996.3025881882711;1009.4296722351847;1015.8374660054355;1020.8264595018716;1033.431921674623;18659.626807463505;19373.4187974361;19424.8057519423;19436.514087147934;19513.121909513822;53484.12805551787;53971.46028539664;54229.72885135707;54421;55146.44853551574;55478.78084876721;74594.2540574609;78801;80590;81715.77426338491;7625.6949722010495;7922.405819563671;8134.06458257308;8361.038480574569;8384.916150838657;10890.727731806768;11850.407479625812;12801.61595152147;13512.189268643913;13707.040507164802;11368.602098926498;11526.308421494738;11583.826242606536;11631.302121872715;11835.881641183478;10554.841677374692;10753.354798712102;11132.998030177323;11171.38557379352;11804.996575291196;11526.942365288456;11616.477258523555;11840.585579504675;12032.879671203176;12110.394480276127;7372.668229929559;8198.467099638505;8215.753527393948;8260.958695206726;8495.787605309917;8468;11179.776404472028;11497.310161390344;11815;11862.40687965581 08:59:56 INFO - PROCESS | 1651 | |3;mootools.html;1554.5802633289015;1576.1725094325477;1815.4735126813225;1901.106479954421;1970.7930667279927;8485.91514084859;8982.19160275575;8991.955040224799;8997.640094396216;8998.370114092007;7757.456978011542;8065.717699880491;8074.434789564718;8133.552654603995;8163;1984.2162345873444;2004.9398518044409;2112.091800525777;2353.4587044979667;2363.1728894886824;2342.9414264643433;2350.5063936573342;2373.074500944633;2376.370261880603;2378.15575470708;2047.9795202048012;2370.63255195445;2377.09670325277;2379.7501262367464;2385.7614238576175;2176.5646870625937;2211.9824880554966;2317.629179331307;2361.2562042956415;2364.598018336887;2330.8368414211013;2333.195047708538;2335.322756400642;2346.97653023471;2363.9054437822465;8746.86879696805;8764.1674040966;8828.24959878411;8833.205011548927;8851.911480885174;5232.084385232569;5340.652857564246;5397.865053373658;5400.324959380077;5424.864378390533;3518.243577630796;3628.455731640249;3666.6699997000396;3681.3925702259094;3689.409694448875;1033.5968972100886;1078.9676309710642;1103.0568863621568;1105.220819322384;1105.8396532502823;11736.061115110771;11740.823887641693;11751.647450576496;11766.823497647541;11818.527258909633;4590.609798167126;4657.790399432034;4799.256115302133;4850.272459131088;4903.926441103386;9081.68214112503;9121.77195570116;9136.406133601295;9149.085091490797;9152.496612686233;5276.841694749163;5284.497972692588;5284.894302113936;5290.894182116336;5299.8410047698635;4351.151525452569;4757.26262429324;4823.662343635911;4946.802127914915;4951.7276549789385;3533.3286675531567;3581;3703.2408356287438;3785.8674946377296;3789;1145.1583086431467;1172.1736175996073;1177.6349331707197;1179.8525184351956;1187.6140254417348;852.522587351073;872.8996165441056;902.9729108126767;907.8955920069144;944.0182210501002;5833.183354330397;5958.255218097738;6037.09443583466;6053.213082299361;6142.324344322121;903.8011637439753;961.6057416459219;965.7344230336713;988.3723835364596;992.3252188511736;3728.2729867675544;3857.5370955485523;3992.680585553149;4005.6995725320717;4101.482451492907;9758.512074396253;9946.756655418072;10148.949255253825;10149.98500149979;10159.949200253952;10038.196944244442;10118;10125.443100629389;10129;10150.238732095122;8222.876656850152;8332.875006874901;8357.749267522106;8358.874616880752;8384.958075209706;5759.65442073477;5901.05583106718;5904.940950590439;6050.727717252822;6234.376562343729;9686.225101991964;9816.705498835046;10093.798124037625;10094.54574544162;10129.544170512492;10317.587296508056;10323.690289291333;10325.122364599094;10337.37975721429;10353.378797272188;7430.959665646706;7474.327310542078;7529.88705169412;7607.4674772765375;7619.580923049286;8029.478083924526;8145.551994640352;8154.959225203836;8281.751547453587;8299.95850020746;7321.816954576178;7446.404287657083;7475.02824632816;7538.509996850187;7604.201558836464;7321.5607063574225;7347.889781653173;7348.338649521569;7364;7415.592142432218;6899.448044156556;6921.2386637470845;6985.336393042653;6996.965015174892;7002.934134430458;4987.12725273083;4993.4756850530175;5048.8990220194655;5080.745962701776;5151.587872970228;4939.135651260943;4974.079795237748;5057.443681194992;5209.4009188943755;5231.476852314738 08:59:56 INFO - PROCESS | 1651 | |4;prototype.html;295.3296018039051;305.136463807425;305.7859498351154;310.61328645835925;312.49064025638205;4183.803503160842;5242.213667949804;5259.395169555498;5274.973625131869;5344;4655.557722016411;5204.401493828211;5248.317718696574;5257.447967963366;5304.443033481487;3267.7875938064067;3913.941290880639;3985.9202815943663;4001.539438105085;4080.83676652935;3995.6603688686496;4029.2747305485;4059.6143366380147;4062.5328087270045;4104.384342348657;4000.6999475039343;4000.739951903117;4004.659603933669;4036.9596304036877;4039.6970227233073;3840;4013.5183777946686;4041.50464328199;4067.1865626874446;4132.483439570054;3144.937101257985;3334.883279085245;3518.4194607889667;3580.4629305604108;3591.407417776064;5130.999455106218;5200.373811225345;5213;5213.113770658997;5219.269302297681;3980.8009599520196;4169.541350451448;4328.394024836525;4694.366260554815;4738.502457242009;4560.475545312298;4930.482299358551;5071.641686615704;5572.0527510323345;5586.301712285965;3644.2143349758426;3925.1953349563273;3925.450436938831;3948.2893079245723;3954.9011274718073;5111.642185047048;5450.31871016119;5847.678377689225;5858.502027327682;5881.411858814127;3436.6563343665935;3802.650059228934;3960.32674445345;3986.800659967048;4013.6989725770686;3480.7027361940995;4035.858744943913;4040.070783719761;4044.919101617951;4076.2458945095254;3930.312195365857;4300.752781693336;4640.396748422683;4701.743163281576;4704.388429504211;5447.1829225616475;5458.744488767606;5482.5613950883835;5667.489925906688;5737.08206686928;1334.8665133486768;1359.7484465373936;1372.8901687864948;1386.2167875150508;1392.693607406369;808.1756608259542;813.9918600814035;829.4940086547201;854.4275335525213;864.3085531574715;4283.5502272260965;5110.170888510396;5139.871503212375;5179.663321884065;5298.708571028554;5081.00920320534;5260.526552610207;5399.2980912481125;5658.292713410824;5759.107338362561;4908.735690765601;5019.824306149268;5244.265802787613;5253.789848406021;5296.629235953446;2869.708631116006;2979.716926891942;2992.102369289248;2995.9400811984074;2999.5853338062634;5219.817306394334;5908.586398952118;5917.497012753878;5963.522918166536;5973.850653733692;5004.824831130893;5012.523810238022;5380.551848908815;5852;5887.61730487517;5010.674306170087;5058.974705126451;5509.724513774296;5545.4731800478085;6026.969865150646;4669.346291519258;4846.006568653488;5449.3460784706085;5779.739911704068;5795.594308398372;5672.858178545404;5685.260916080883;5695.572832037531;5703.144528320785;5771.480566749013;5715.713714514061;5749.981259838618;5775.267419773967;6064.3935606439;6083.330833608211;3406.6423025581958;3646.741874053447;3677.3196958562753;3816.3512202925012;3828.138668799609;4418.668599855088;5155.2782610435315;5692.914606280992;5704.3725190228215;5713.428657134254;4930.383702037246;5216.269722238965;5594.524465420485;5691.743871525874;5704.80033198836;5226.372835259793;5408;5603.411641777718;5639.746211420414;5658.453695836807;4676.906461870812;5556.110922286442;5570.888582228414;5593.552515798645;5608.074667679787;4746.691465054761;5315.495027972336;5512.6692398456225;5515.227868098389;5518.365387980273;4632.475300960917;5461.808836690697;5493.917591236055;5541.972290138524;5560.054790685674 08:59:56 INFO - PROCESS | 1651 | |5;yui.html;636.8758092172026;699.176218119246;704.8519810839723;753.5666991479899;799.3485309472779;14950.925245373772;15118.017328873615;15687.843121568781;15725.764113538306;16173.19134043299;14420.423183072664;14455.710885782279;14623.926880365583;14661.486847960323;14678.926605366958;879.2306731609841;911.3939230411768;911.4667919267237;912.8083102548472;913.5477938420491;717.6842189436658;742.2317900972495;748.0611832150671;901.2204443156655;906.0758026812647;925.6019911438079;956.0391806234725;962.7063745557602;964.3056998960772;964.7250533597926;940.1867384712209;943.6838659049226;945.2249155692335;945.3382632157449;949.126803340928;558.0568838662647;578.4504720515528;700.3626699703245;701.5159539917491;703.0192880931072;14039.438422463089;14199.07705999117;14275.928620356832;14844.109353438827;14909.925450372788;14239.572812815632;14591.854081459156;14696.853031469655;14851.405943762236;14904.403823847033;11723.355215463145;11837.526498940031;11850.229735067192;11982;12093.57672481459;3551.254236610315;3597;3740.1584643455276;3785.0726571989762;3807.6192380761704;104288.95711042869;106100.46949765201;106108;108023.45988270166;110341;9666.661666841626;11231.045361144232;11453.141014423785;11495.712607184887;11542.13433992454;20863.1654733812;23306.65040024401;23310.417239569146;23323.533529329317;23327.18354857572;7756.418268629763;8124.146964568635;8222.013358397031;8226.218509241613;8233.46482478637;4369.9126017479475;4401.295792673221;4523.909521809545;4595;4596.37948876904;3515.1036485695986;3639.381305178073;3716.47969284295;3728.9440658390145;3732.384156614128;640.8654182621608;648.3807963394946;658.3317932298721;660.986780264392;671.8723442545901;604.7157835817159;609.969501524922;613.5705006495471;644.6687590126375;659.3538332434146;8833.867491987625;9352.307882687786;9532.52337383128;9654.972455784224;9687.9031209687;582.5397935630889;617.4689766800548;654.1365397675119;670.9563878347892;671.3923898871432;4297.312430011183;4621.2837010263465;4625.7687115644085;4654.464736555272;4716.716996980193;95430;108219;121944;126343;130068.48972653989;79346.6032669833;89996;107133.39299910508;108886;124473.37763311306;74194;90875.54562227147;111836.88163118427;113242;113399.99999999836;9442.205980863613;9602.567884445078;9758.77960273094;9831.402206341463;9950.502474876082;58261;60530.4867378319;60775.392246076975;62527.6873615629;64473.67763161154;85810;92434.44569428821;93556.53221733848;93957.1208575811;94663.58004629798;12902.741945161231;12953.664969759913;13075.41160647754;13150.079494435298;13284.07011509185;14409.42362305538;14411.42354305816;14448.060876043024;14895.106293622417;14898.553043408716;13182.802257965946;13266.602001939957;13485.50595415001;13641.522546710818;13850.238236897067;13540.458381664623;13646.727065458834;13765;13793.724125517232;13842.792358114435;13200.933995329962;13389.66525836862;13411.075803905234;13416;13629.727405452033;10652.680419587425;10906.781864362509;11231.66305010851;11359.716007099889;11449.828252576051;10479.738006549593;11185.264681658353;11495.885041149815;11579.189456738284;11762.76474470523 08:59:56 INFO - PROCESS | 1651 | __end_tp_report 08:59:56 INFO - PROCESS | 1651 | __start_cc_report 08:59:56 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,2168 08:59:56 INFO - PROCESS | 1651 | __end_cc_report 08:59:56 INFO - PROCESS | 1651 | __startTimestamp1476719996065__endTimestamp 08:59:56 INFO - PROCESS | 1651 | 08:59:56 INFO - PROCESS | 1651 | ------- Summary: start ------- 08:59:56 INFO - PROCESS | 1651 | Number of tests: 6 08:59:56 INFO - PROCESS | 1651 | 08:59:56 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5666.23 Median:5834.69 stddev:634.00 (10.9%) stddev-sans-first:605.65 08:59:56 INFO - PROCESS | 1651 | Values: 3197.9 3341.4 3351.3 3376.9 3396.8 5534.9 5638.4 5670.6 5689.6 5786.0 5639.2 5685.7 5695.3 5695.5 5706.5 5115.6 5168.5 5173.0 5189.5 5211.1 5205.1 5213.8 5293.1 5294.1 5331.9 5277.9 5292.6 5303.2 5310.8 5354.1 5302.0 5307.6 5311.4 5333.8 5338.9 4936.0 5012.9 5174.1 5182.8 5186.2 5586.1 5587.3 5623.5 5629.1 5643.0 6169.0 6169.3 6256.9 6260.0 6272.7 6211.5 6261.9 6269.9 6281.6 6335.9 5809.3 5823.2 5834.7 5834.8 5887.5 5103.6 6223.0 6300.6 6315.8 6316.1 4738.2 5411.7 5809.7 5822.0 5878.2 5568.9 5930.4 5966.0 5968.5 5996.9 5851.0 5852.7 6001.8 6063.7 6066.6 6112.0 6125.3 6125.5 6139.2 6140.0 4883.2 4892.1 4892.1 4919.3 4938.8 4403.6 4441.1 4441.4 4474.1 4491.2 4849.7 5739.1 5753.3 5754.5 5778.1 6091.3 6111.9 6126.8 6206.6 6295.5 5668.2 5674.5 5675.1 5683.9 5685.0 5309.5 5354.1 5363.5 5366.9 5373.8 6283.0 6299.9 6303.4 6305.2 6316.1 6176.8 6203.3 6220.6 6232.8 6292.4 6270.2 6287.7 6295.0 6298.8 6345.9 6103.8 6134.8 6149.9 6152.7 6159.1 5458.9 6029.4 6080.8 6116.0 6143.9 6106.8 6120.1 6142.5 6147.0 6150.3 6013.4 6131.6 6145.8 6146.6 6160.4 5715.7 5878.7 5987.7 5989.2 6000.8 4461.3 5879.3 5978.0 6031.7 6083.1 5025.8 5869.1 5872.3 5885.3 5905.9 08:59:56 INFO - PROCESS | 1651 | 08:59:56 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:66505.83 Median:40224.30 stddev:144848.37 (360.1%) stddev-sans-first:145169.90 08:59:56 INFO - PROCESS | 1651 | Values: 488.5 510.9 523.6 531.0 550.8 26801.9 41060.6 41898.2 42255.0 44201.3 42919.4 43486.1 43523.0 43559.1 43676.6 9463.9 9711.7 9715.0 9715.8 9727.3 9561.3 9673.5 9680.3 9680.4 9695.1 9669.1 9711.5 9720.0 9729.8 9769.3 9594.6 9665.1 9673.1 9684.7 9700.5 9570.0 9644.0 9654.0 9654.1 9658.8 41174.4 41528.0 41575.0 41751.6 41788.0 3417.4 3440.8 3442.2 3496.2 3894.5 30716.4 31039.8 31701.2 31822.7 32076.5 3132.3 3241.4 3247.3 3253.6 3263.2 346656.9 346769.0 347593.0 349415.0 357631.0 62841.7 63253.0 63260.7 63285.7 63501.0 34398.5 34816.8 35338.0 65390.7 65779.0 16485.8 16896.7 16906.0 16919.4 16950.5 12868.7 12933.3 12943.2 12957.8 12979.9 19517.1 19688.1 19781.5 19793.3 19802.9 7730.8 7773.0 7896.5 7900.8 7992.3 799.4 854.7 968.0 1014.6 1094.2 29080.4 29080.7 30320.0 30320.7 30862.7 913.5 1075.4 1091.9 1110.6 1112.8 15160.8 15464.9 15697.5 15793.9 16150.9 657557.0 661056.8 663189.0 666145.0 670226.0 60369.0 60664.4 61105.4 61124.4 61311.5 1355.2 1381.0 1407.2 1410.1 1411.9 29817.6 29875.6 30123.8 30227.1 30350.5 52502.7 52778.5 52806.7 52905.5 52996.7 553011.0 558586.5 563617.0 566892.0 568302.0 26293.3 26338.3 26339.7 26834.6 27229.6 45818.0 47419.0 48349.5 49059.5 49218.0 33208.0 39116.8 39608.2 40099.0 40628.4 39262.6 40075.8 40349.6 40468.4 40965.0 30566.0 33373.8 33694.5 39072.0 39996.0 31627.0 32184.4 35029.0 35084.5 35123.9 21651.6 24486.7 24970.6 25801.5 25873.4 08:59:56 INFO - PROCESS | 1651 | 08:59:56 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:34690.95 Median:23721.08 stddev:100642.94 (424.3%) stddev-sans-first:100892.23 08:59:56 INFO - PROCESS | 1651 | Values: 346.3 358.5 366.8 371.9 393.8 14662.3 15054.3 15390.2 19454.7 19826.8 19621.3 20485.8 21048.4 21100.5 21299.3 5813.0 6122.8 6129.3 6140.0 6142.8 5018.0 6597.4 6658.9 6673.0 6706.8 5721.1 6009.1 6697.3 6697.9 6707.0 6082.3 6107.3 6129.4 6133.1 6136.2 5731.2 5818.6 5837.2 5911.6 5924.9 13214.5 13386.7 13627.3 21282.5 23761.3 2226.4 2305.0 2362.6 2385.8 2905.4 9556.8 9787.1 9999.8 10269.2 10464.4 1776.4 1827.8 1876.9 1894.5 1901.6 97020.0 100547.0 110690.4 119874.0 121070.8 11379.9 11397.5 11524.7 11592.0 12068.4 14582.7 14583.9 14588.1 14598.5 14633.8 6770.3 8584.6 8625.3 8627.7 8747.0 8590.8 8596.0 8615.7 8791.7 8803.0 4412.5 4513.7 4527.7 4573.6 4592.3 1718.3 1829.9 1889.8 1890.0 1898.6 735.5 737.5 790.9 790.9 999.2 18186.8 19687.5 23035.1 23272.7 23680.9 816.4 881.4 946.9 954.9 956.3 10805.6 11530.7 11611.1 11661.9 11684.4 592416.0 593921.0 599547.0 616855.0 618406.0 105594.5 127617.0 134252.0 134716.3 135328.0 996.3 1009.4 1015.8 1020.8 1033.4 18659.6 19373.4 19424.8 19436.5 19513.1 53484.1 53971.5 54229.7 54421.0 55146.4 55478.8 74594.3 78801.0 80590.0 81715.8 7625.7 7922.4 8134.1 8361.0 8384.9 10890.7 11850.4 12801.6 13512.2 13707.0 11368.6 11526.3 11583.8 11631.3 11835.9 10554.8 10753.4 11133.0 11171.4 11805.0 11526.9 11616.5 11840.6 12032.9 12110.4 7372.7 8198.5 8215.8 8261.0 8495.8 8468.0 11179.8 11497.3 11815.0 11862.4 08:59:56 INFO - PROCESS | 1651 | 08:59:56 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5660.95 Median:5053.17 stddev:3132.42 (62.0%) stddev-sans-first:3126.01 08:59:56 INFO - PROCESS | 1651 | Values: 1554.6 1576.2 1815.5 1901.1 1970.8 8485.9 8982.2 8992.0 8997.6 8998.4 7757.5 8065.7 8074.4 8133.6 8163.0 1984.2 2004.9 2112.1 2353.5 2363.2 2342.9 2350.5 2373.1 2376.4 2378.2 2048.0 2370.6 2377.1 2379.8 2385.8 2176.6 2212.0 2317.6 2361.3 2364.6 2330.8 2333.2 2335.3 2347.0 2363.9 8746.9 8764.2 8828.2 8833.2 8851.9 5232.1 5340.7 5397.9 5400.3 5424.9 3518.2 3628.5 3666.7 3681.4 3689.4 1033.6 1079.0 1103.1 1105.2 1105.8 11736.1 11740.8 11751.6 11766.8 11818.5 4590.6 4657.8 4799.3 4850.3 4903.9 9081.7 9121.8 9136.4 9149.1 9152.5 5276.8 5284.5 5284.9 5290.9 5299.8 4351.2 4757.3 4823.7 4946.8 4951.7 3533.3 3581.0 3703.2 3785.9 3789.0 1145.2 1172.2 1177.6 1179.9 1187.6 852.5 872.9 903.0 907.9 944.0 5833.2 5958.3 6037.1 6053.2 6142.3 903.8 961.6 965.7 988.4 992.3 3728.3 3857.5 3992.7 4005.7 4101.5 9758.5 9946.8 10148.9 10150.0 10159.9 10038.2 10118.0 10125.4 10129.0 10150.2 8222.9 8332.9 8357.7 8358.9 8385.0 5759.7 5901.1 5904.9 6050.7 6234.4 9686.2 9816.7 10093.8 10094.5 10129.5 10317.6 10323.7 10325.1 10337.4 10353.4 7431.0 7474.3 7529.9 7607.5 7619.6 8029.5 8145.6 8155.0 8281.8 8300.0 7321.8 7446.4 7475.0 7538.5 7604.2 7321.6 7347.9 7348.3 7364.0 7415.6 6899.4 6921.2 6985.3 6997.0 7002.9 4987.1 4993.5 5048.9 5080.7 5151.6 4939.1 4974.1 5057.4 5209.4 5231.5 08:59:56 INFO - PROCESS | 1651 | 08:59:56 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4505.41 Median:5110.91 stddev:1372.96 (26.9%) stddev-sans-first:1339.96 08:59:56 INFO - PROCESS | 1651 | Values: 295.3 305.1 305.8 310.6 312.5 4183.8 5242.2 5259.4 5275.0 5344.0 4655.6 5204.4 5248.3 5257.4 5304.4 3267.8 3913.9 3985.9 4001.5 4080.8 3995.7 4029.3 4059.6 4062.5 4104.4 4000.7 4000.7 4004.7 4037.0 4039.7 3840.0 4013.5 4041.5 4067.2 4132.5 3144.9 3334.9 3518.4 3580.5 3591.4 5131.0 5200.4 5213.0 5213.1 5219.3 3980.8 4169.5 4328.4 4694.4 4738.5 4560.5 4930.5 5071.6 5572.1 5586.3 3644.2 3925.2 3925.5 3948.3 3954.9 5111.6 5450.3 5847.7 5858.5 5881.4 3436.7 3802.7 3960.3 3986.8 4013.7 3480.7 4035.9 4040.1 4044.9 4076.2 3930.3 4300.8 4640.4 4701.7 4704.4 5447.2 5458.7 5482.6 5667.5 5737.1 1334.9 1359.7 1372.9 1386.2 1392.7 808.2 814.0 829.5 854.4 864.3 4283.6 5110.2 5139.9 5179.7 5298.7 5081.0 5260.5 5399.3 5658.3 5759.1 4908.7 5019.8 5244.3 5253.8 5296.6 2869.7 2979.7 2992.1 2995.9 2999.6 5219.8 5908.6 5917.5 5963.5 5973.9 5004.8 5012.5 5380.6 5852.0 5887.6 5010.7 5059.0 5509.7 5545.5 6027.0 4669.3 4846.0 5449.3 5779.7 5795.6 5672.9 5685.3 5695.6 5703.1 5771.5 5715.7 5750.0 5775.3 6064.4 6083.3 3406.6 3646.7 3677.3 3816.4 3828.1 4418.7 5155.3 5692.9 5704.4 5713.4 4930.4 5216.3 5594.5 5691.7 5704.8 5226.4 5408.0 5603.4 5639.7 5658.5 4676.9 5556.1 5570.9 5593.6 5608.1 4746.7 5315.5 5512.7 5515.2 5518.4 4632.5 5461.8 5493.9 5542.0 5560.1 08:59:56 INFO - PROCESS | 1651 | 08:59:56 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:22937.78 Median:3736.27 stddev:34528.81 (924.2%) stddev-sans-first:34585.07 08:59:56 INFO - PROCESS | 1651 | Values: 636.9 699.2 704.9 753.6 799.3 14950.9 15118.0 15687.8 15725.8 16173.2 14420.4 14455.7 14623.9 14661.5 14678.9 879.2 911.4 911.5 912.8 913.5 717.7 742.2 748.1 901.2 906.1 925.6 956.0 962.7 964.3 964.7 940.2 943.7 945.2 945.3 949.1 558.1 578.5 700.4 701.5 703.0 14039.4 14199.1 14275.9 14844.1 14909.9 14239.6 14591.9 14696.9 14851.4 14904.4 11723.4 11837.5 11850.2 11982.0 12093.6 3551.3 3597.0 3740.2 3785.1 3807.6 104289.0 106100.5 106108.0 108023.5 110341.0 9666.7 11231.0 11453.1 11495.7 11542.1 20863.2 23306.7 23310.4 23323.5 23327.2 7756.4 8124.1 8222.0 8226.2 8233.5 4369.9 4401.3 4523.9 4595.0 4596.4 3515.1 3639.4 3716.5 3728.9 3732.4 640.9 648.4 658.3 661.0 671.9 604.7 610.0 613.6 644.7 659.4 8833.9 9352.3 9532.5 9655.0 9687.9 582.5 617.5 654.1 671.0 671.4 4297.3 4621.3 4625.8 4654.5 4716.7 95430.0 108219.0 121944.0 126343.0 130068.5 79346.6 89996.0 107133.4 108886.0 124473.4 74194.0 90875.5 111836.9 113242.0 113400.0 9442.2 9602.6 9758.8 9831.4 9950.5 58261.0 60530.5 60775.4 62527.7 64473.7 85810.0 92434.4 93556.5 93957.1 94663.6 12902.7 12953.7 13075.4 13150.1 13284.1 14409.4 14411.4 14448.1 14895.1 14898.6 13182.8 13266.6 13485.5 13641.5 13850.2 13540.5 13646.7 13765.0 13793.7 13842.8 13200.9 13389.7 13411.1 13416.0 13629.7 10652.7 10906.8 11231.7 11359.7 11449.8 10479.7 11185.3 11495.9 11579.2 11762.8 08:59:56 INFO - PROCESS | 1651 | -------- Summary: end -------- 08:59:56 INFO - PROCESS | 1651 | 08:59:56 INFO - TEST-INFO | 1651: exit 0 08:59:56 INFO - TEST-OK | dromaeo_css | took 1120600ms 08:59:56 INFO - TEST-START | kraken 08:59:56 INFO - operating with platform_type : mac_ 08:59:56 INFO - Initialising browser for kraken test... 08:59:56 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpNE6HKZ/profile http://localhost:49273/getInfo.html) 08:59:59 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 08:59:59 INFO - PROCESS | 1661 | colorDepth:24 08:59:59 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 08:59:59 INFO - PROCESS | 1661 | __metrics 08:59:59 INFO - PROCESS | 1661 | 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" 08:59:59 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:59:59 INFO - TEST-INFO | 1661: exit 0 08:59:59 INFO - Browser initialized. 08:59:59 INFO - Running cycle 1/1 for kraken test... 08:59:59 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpNE6HKZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:00:11 INFO - PROCESS | 1662 | RSS: Main: 240431104 09:00:11 INFO - PROCESS | 1662 | 09:00:14 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 09:00:15 INFO - PROCESS | 1662 | RSS: Main: 290816000 09:00:15 INFO - PROCESS | 1662 | 09:00:18 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 09:00:18 INFO - PROCESS | 1662 | RSS: Main: 290205696 09:00:18 INFO - PROCESS | 1662 | 09:00:22 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 09:00:22 INFO - PROCESS | 1662 | RSS: Main: 383705088 09:00:22 INFO - PROCESS | 1662 | 09:00:24 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 09:00:25 INFO - PROCESS | 1662 | RSS: Main: 287109120 09:00:25 INFO - PROCESS | 1662 | 09:00:27 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:00:27 INFO - PROCESS | 1662 | RSS: Main: 289878016 09:00:27 INFO - PROCESS | 1662 | 09:00:30 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 09:00:31 INFO - PROCESS | 1662 | RSS: Main: 326860800 09:00:31 INFO - PROCESS | 1662 | 09:00:34 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 09:00:34 INFO - PROCESS | 1662 | RSS: Main: 327925760 09:00:34 INFO - PROCESS | 1662 | 09:00:37 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 09:00:38 INFO - PROCESS | 1662 | RSS: Main: 326160384 09:00:38 INFO - PROCESS | 1662 | 09:00:39 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:00:40 INFO - PROCESS | 1662 | RSS: Main: 303611904 09:00:40 INFO - PROCESS | 1662 | 09:00:42 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:00:42 INFO - PROCESS | 1662 | RSS: Main: 304824320 09:00:42 INFO - PROCESS | 1662 | 09:00:45 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:00:45 INFO - PROCESS | 1662 | RSS: Main: 307068928 09:00:45 INFO - PROCESS | 1662 | 09:00:49 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:00:49 INFO - PROCESS | 1662 | RSS: Main: 316342272 09:00:49 INFO - PROCESS | 1662 | 09:00:52 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:00:53 INFO - PROCESS | 1662 | RSS: Main: 316329984 09:00:53 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 09:00:55 INFO - PROCESS | 1662 | RSS: Main: 360562688 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | __start_tp_report 09:00:55 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 09:00:55 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 09:00:55 INFO - PROCESS | 1662 | |i|pagename|runs| 09:00:55 INFO - PROCESS | 1662 | |0;ai-astar;112;112;111;111;112;113;112;112;112;112 09:00:55 INFO - PROCESS | 1662 | |1;audio-beat-detection;149;116;114;148;110;114;113;114;146;112 09:00:55 INFO - PROCESS | 1662 | |2;audio-dft;184;183;185;185;183;185;178;184;182;185 09:00:55 INFO - PROCESS | 1662 | |3;audio-fft;84;58;59;86;61;59;59;57;80;59 09:00:55 INFO - PROCESS | 1662 | |4;audio-oscillator;82;70;95;71;68;69;92;67;69;90 09:00:55 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;88;87;90;86;73;114;134;108;88;85 09:00:55 INFO - PROCESS | 1662 | |6;imaging-darkroom;105;97;99;143;97;96;97;96;97;96 09:00:55 INFO - PROCESS | 1662 | |7;imaging-desaturate;82;81;83;80;80;82;83;82;81;81 09:00:55 INFO - PROCESS | 1662 | |8;json-parse-financial;55;53;52;52;54;55;54;56;55;54 09:00:55 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;67;73;66;75;70;68;68;70;69;71 09:00:55 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;103;108;107;72;100;105;107;106;87;90 09:00:55 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;148;145;146;146;145;146;149;157;215;142 09:00:55 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;179;173;173;171;171;189;240;199;171;171 09:00:55 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;69;69;69;69;70;68;68;67;68;66 09:00:55 INFO - PROCESS | 1662 | __end_tp_report 09:00:55 INFO - PROCESS | 1662 | __start_cc_report 09:00:55 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1979 09:00:55 INFO - PROCESS | 1662 | __end_cc_report 09:00:55 INFO - PROCESS | 1662 | __startTimestamp1476720055417__endTimestamp 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | ------- Summary: start ------- 09:00:55 INFO - PROCESS | 1662 | Number of tests: 14 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 09:00:55 INFO - PROCESS | 1662 | Values: 112.0 112.0 111.0 111.0 112.0 113.0 112.0 112.0 112.0 112.0 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:123.60 Median:115.00 stddev:16.69 (14.5%) stddev-sans-first:14.96 09:00:55 INFO - PROCESS | 1662 | Values: 149.0 116.0 114.0 148.0 110.0 114.0 113.0 114.0 146.0 112.0 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.50 stddev:2.17 (1.2%) stddev-sans-first:2.29 09:00:55 INFO - PROCESS | 1662 | Values: 184.0 183.0 185.0 185.0 183.0 185.0 178.0 184.0 182.0 185.0 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:66.20 Median:60.00 stddev:11.95 (19.9%) stddev-sans-first:10.80 09:00:55 INFO - PROCESS | 1662 | Values: 84.0 58.0 59.0 86.0 61.0 59.0 59.0 57.0 80.0 59.0 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:76.50 stddev:11.24 (14.7%) stddev-sans-first:11.79 09:00:55 INFO - PROCESS | 1662 | Values: 82.0 70.0 95.0 71.0 68.0 69.0 92.0 67.0 69.0 90.0 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:95.30 Median:86.50 stddev:17.96 (20.8%) stddev-sans-first:18.85 09:00:55 INFO - PROCESS | 1662 | Values: 88.0 87.0 90.0 86.0 73.0 114.0 134.0 108.0 88.0 85.0 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:102.30 Median:97.00 stddev:14.55 (15.0%) stddev-sans-first:15.40 09:00:55 INFO - PROCESS | 1662 | Values: 105.0 97.0 99.0 143.0 97.0 96.0 97.0 96.0 97.0 96.0 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:81.50 Median:82.00 stddev:1.08 (1.3%) stddev-sans-first:1.13 09:00:55 INFO - PROCESS | 1662 | Values: 82.0 81.0 83.0 80.0 80.0 82.0 83.0 82.0 81.0 81.0 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.50 stddev:1.33 (2.4%) stddev-sans-first:1.36 09:00:55 INFO - PROCESS | 1662 | Values: 55.0 53.0 52.0 52.0 54.0 55.0 54.0 56.0 55.0 54.0 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:70.00 stddev:2.75 (3.9%) stddev-sans-first:2.74 09:00:55 INFO - PROCESS | 1662 | Values: 67.0 73.0 66.0 75.0 70.0 68.0 68.0 70.0 69.0 71.0 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:98.50 Median:107.50 stddev:11.84 (11.0%) stddev-sans-first:12.45 09:00:55 INFO - PROCESS | 1662 | Values: 103.0 108.0 107.0 72.0 100.0 105.0 107.0 106.0 87.0 90.0 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:153.90 Median:147.00 stddev:21.83 (14.9%) stddev-sans-first:23.05 09:00:55 INFO - PROCESS | 1662 | Values: 148.0 145.0 146.0 146.0 145.0 146.0 149.0 157.0 215.0 142.0 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.70 Median:176.00 stddev:21.93 (12.5%) stddev-sans-first:23.19 09:00:55 INFO - PROCESS | 1662 | Values: 179.0 173.0 173.0 171.0 171.0 189.0 240.0 199.0 171.0 171.0 09:00:55 INFO - PROCESS | 1662 | 09:00:55 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:1.16 (1.7%) stddev-sans-first:1.20 09:00:55 INFO - PROCESS | 1662 | Values: 69.0 69.0 69.0 69.0 70.0 68.0 68.0 67.0 68.0 66.0 09:00:55 INFO - PROCESS | 1662 | -------- Summary: end -------- 09:00:55 INFO - PROCESS | 1662 | 09:00:56 INFO - TEST-INFO | 1662: exit 0 09:00:56 INFO - TEST-OK | kraken | took 59611ms 09:00:56 INFO - SUITE-END | took 1180s 09:00:56 INFO - Completed test suite (00:19:40) 09:00:56 INFO - javascript benchmark 09:00:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3197.872085116595, 3341.3985482613134, 3351.2962277921642, 3376.9362650764992, 3396.7622266441344, 5534.889302213964, 5638.40796716345, 5670.574706896979, 5689.630174038684, 5786.0, 5639.182318563806, 5685.744141513632, 5695.316562012565, 5695.45893140152, 5706.457886500786, 5115.616328775347, 5168.534831865131, 5173.0, 5189.455107213745, 5211.088059589576, 5205.115130427836, 5213.791448342062, 5293.126634105368, 5294.073537131006, 5331.866703332409, 5277.947220527803, 5292.55013323866, 5303.151495760679, 5310.8141215057485, 5354.063038968185, 5302.019126461598, 5307.575393968493, 5311.389190243133, 5333.786648534054, 5338.866528336784, 4935.9753201234125, 5012.8997420051755, 5174.146265866128, 5182.818601348972, 5186.170212765977, 5586.1062230043, 5587.329520457572, 5623.493885550321, 5629.127485239795, 5642.984262832689, 6168.982117950578, 6169.259688837368, 6256.937430625681, 6260.029695397221, 6272.749090036393, 6211.534134939852, 6261.937380626181, 6269.905951410732, 6281.623102613812, 6335.936640633627, 5809.302883653946, 5823.184754134425, 5834.687195381048, 5834.824955251349, 5887.470127688529, 5103.642745007888, 6222.968885155546, 6300.558960872787, 6315.842103947346, 6316.147320111818, 4738.1708201064675, 5411.729413529308, 5809.651420914674, 5821.970890145522, 5878.2064421302575, 5568.916466253009, 5930.406959304121, 5966.0, 5968.46283834457, 5996.940030599638, 5850.970745146247, 5852.736626851802, 6001.849953751192, 6063.727132279058, 6066.636001839904, 6112.047575716847, 6125.2659746757545, 6125.540584456095, 6139.185504090456, 6139.960719443904, 4883.194272944996, 4892.0705066037335, 4892.119418504632, 4919.311296418504, 4938.827141050046, 4403.647708183337, 4441.067375851043, 4441.356003379492, 4474.0604473060375, 4491.1915855145735, 4849.733264670477, 5739.053056245672, 5753.252077229933, 5754.482096611325, 5778.075507918713, 6091.299500557493, 6111.865232675556, 6126.792505495304, 6206.565540412128, 6295.527835412271, 5668.206451096849, 5674.517665998354, 5675.091985282335, 5683.857903552444, 5684.971575142098, 5309.522143007071, 5354.089804733205, 5363.517283444508, 5366.946330536723, 5373.811916582901, 6282.968585157045, 6299.937000629935, 6303.432691057827, 6305.211848518935, 6316.05259211122, 6176.7838125665385, 6203.2556093269095, 6220.595661282002, 6232.781852635136, 6292.370762923671, 6270.216222972129, 6287.685615719287, 6295.0, 6298.842528936813, 6345.904811427925, 6103.816885493442, 6134.8159555213415, 6149.877002460015, 6152.692365381802, 6159.076138579249, 5458.890822183614, 6029.366916473882, 6080.817575472743, 6116.0010150213275, 6143.938560614337, 6106.794380079809, 6120.112583675431, 6142.498775697809, 6147.047207682816, 6150.2927163375, 6013.428724271187, 6131.552947516549, 6145.832291864532, 6146.6170111724505, 6160.353162917829, 5715.657060576379, 5878.706064696834, 5987.670678112746, 5989.191459153133, 6000.819975400745, 4461.286194208911, 5879.265091863517, 5978.0, 6031.698415079316, 6083.1483592298, 5025.824096156617, 5869.090291004901, 5872.324682661378, 5885.323187833454, 5905.91141132892], "value": 5627.98143454339, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.5334505547203, 510.8518529626408, 523.5915985531287, 530.9654872433294, 550.8237364043505, 26801.865990670045, 41060.58939410605, 41898.162036759284, 42255.0, 44201.33697994533, 42919.35620965688, 43486.13027739451, 43523.0, 43559.12881742363, 43676.56323436765, 9463.858042129355, 9711.660091896785, 9715.0, 9715.757106072335, 9727.270454715925, 9561.28290378221, 9673.516324183762, 9680.273979451535, 9680.370775899579, 9695.078967498077, 9669.12977831995, 9711.465869377182, 9720.027997200259, 9729.805403891918, 9769.316147869651, 9594.616215351378, 9665.130138287588, 9673.081057299545, 9684.661036863676, 9700.514974251259, 9570.0, 9643.98738132493, 9654.0, 9654.082862128087, 9658.806823863553, 41174.38238426396, 41528.0, 41575.0, 41751.58248417538, 41788.0, 3417.4019546579248, 3440.8107554084513, 3442.208292092833, 3496.2133519957806, 3894.4547763313158, 30716.38567228665, 31039.844800776078, 31701.20746981321, 31822.681773182205, 32076.518981068675, 3132.268414971001, 3241.3679332530164, 3247.285597168619, 3253.577034985437, 3263.184203949013, 346656.8670096535, 346769.0, 347593.0, 349415.0, 357631.0, 62841.68579157166, 63253.0, 63260.683696581225, 63285.68357158277, 63501.0, 34398.48402273968, 34816.82591587026, 35338.0, 65390.67304663541, 65779.01331480032, 16485.752713709306, 16896.662066758596, 16905.98564086159, 16919.407820726214, 16950.49148525546, 12868.678283042811, 12933.288669123289, 12943.158694684815, 12957.805632915513, 12979.935100324627, 19517.121729522205, 19688.114034868464, 19781.505462363555, 19793.30723424673, 19802.90098549498, 7730.806729831799, 7773.0, 7896.526208427513, 7900.762977110696, 7992.280694737501, 799.3884678221166, 854.6837670062117, 967.9739476155298, 1014.5688082564881, 1094.195766111907, 29080.418391632047, 29080.7091929078, 30320.0, 30320.696793032228, 30862.691373086433, 913.5386629751932, 1075.3547871277328, 1091.9290246133976, 1110.6279396402135, 1112.8330750387506, 15160.772588411182, 15464.922675386551, 15697.529074127795, 15793.921030394775, 16150.857914973607, 657557.0, 661056.7573377629, 663189.0, 666145.0, 670226.0, 60369.0, 60664.39335606676, 61105.388946110856, 61124.38875611187, 61311.46721332002, 1355.2275203134316, 1380.9792853107213, 1407.183833376639, 1410.1398147130037, 1411.908225965309, 29817.552736708534, 29875.55186672158, 30123.849380752956, 30227.109586855713, 30350.544741829333, 52502.737486312326, 52778.472215277354, 52806.735966319924, 52905.47094529006, 52996.735016324674, 553011.0, 558586.5201457922, 563617.0, 566892.0, 568302.0, 26293.34266643349, 26338.341541461617, 26339.736602634493, 26834.59748103741, 27229.59155612707, 45818.0, 47419.0, 48349.516504835905, 49059.50940490692, 49218.0, 33208.003759887244, 39116.804415977735, 39608.2078358437, 40098.99752506211, 40628.39057414082, 39262.60737392589, 40075.79962100171, 40349.59650403458, 40468.39297410482, 40965.0, 30566.0, 33373.83313083516, 33694.49458258177, 39072.0, 39996.0, 31627.0, 32184.35631287408, 35029.0, 35084.473732893515, 35123.94628161159, 21651.566968660223, 24486.653234071586, 24970.62544061804, 25801.483970320864, 25873.353166170997], "value": 19870.044064760932, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.3384934774578, 358.4802037046282, 366.7799320407755, 371.9497867787849, 393.77358019139, 14662.340194691238, 15054.322555485001, 15390.230488475574, 19454.70817937732, 19826.801731982712, 19621.31325403611, 20485.795142048573, 21048.36854894352, 21100.472488187803, 21299.25452609159, 5812.999999999989, 6122.755089796403, 6129.264488261392, 6139.969300153493, 6142.815715528519, 5018.0, 6597.406233438989, 6658.866822663544, 6673.032410300503, 6706.832329191785, 5721.056025755745, 6009.09863520471, 6697.263301036882, 6697.866042679144, 6706.966465167668, 6082.330943596201, 6107.328193898667, 6129.356417576179, 6133.110698948627, 6136.202293701813, 5731.226284451587, 5818.621789583706, 5837.211976383176, 5911.586188966774, 5924.940750592482, 13214.537491187762, 13386.732265354736, 13627.250501222428, 21282.46793830151, 23761.287161385186, 2226.4322597737473, 2304.9654255186188, 2362.55111528809, 2385.8449200802065, 2905.4274930905763, 9556.75762150916, 9787.070228328297, 9999.75000624983, 10269.229807764448, 10464.42445665488, 1776.422662634649, 1827.839322030507, 1876.8873867567854, 1894.4979580411064, 1901.5531350132708, 97020.0, 100546.9945300545, 110690.44654776755, 119874.0, 121070.78929210771, 11379.886201138048, 11397.487113079931, 11524.711882202844, 11592.0, 12068.396580171131, 14582.708345833023, 14583.92708036453, 14588.051776634486, 14598.489052883311, 14633.780493292608, 6770.255271920085, 8584.613692383859, 8625.309975201968, 8627.6980305689, 8747.0, 8590.785230369165, 8596.0, 8615.6984505542, 8791.692290769792, 8802.955985220033, 4412.536683648235, 4513.72917624937, 4527.728336299832, 4573.611243044312, 4592.288195329729, 1718.2869109319493, 1829.9451016469527, 1889.8299153076289, 1889.9510771521661, 1898.6202759448165, 735.5292612727858, 737.5464089584867, 790.8853216283721, 790.8892755014302, 999.165696643311, 18186.81785683926, 19687.507812304808, 23035.07859685594, 23272.65091023636, 23680.881595592255, 816.4121832280748, 881.3962435731545, 946.9299691348732, 954.8663187153804, 956.32101208143, 10805.621803236849, 11530.656109728085, 11611.129165312635, 11661.88338116625, 11684.357360345262, 592416.0, 593921.0, 599547.0022649947, 616855.0, 618406.0, 105594.4720276409, 127617.0, 134252.0, 134716.32641836925, 135328.0, 996.3025881882711, 1009.4296722351847, 1015.8374660054355, 1020.8264595018716, 1033.431921674623, 18659.626807463505, 19373.4187974361, 19424.8057519423, 19436.514087147934, 19513.121909513822, 53484.12805551787, 53971.46028539664, 54229.72885135707, 54421.0, 55146.44853551574, 55478.78084876721, 74594.2540574609, 78801.0, 80590.0, 81715.77426338491, 7625.6949722010495, 7922.405819563671, 8134.06458257308, 8361.038480574569, 8384.916150838657, 10890.727731806768, 11850.407479625812, 12801.61595152147, 13512.189268643913, 13707.040507164802, 11368.602098926498, 11526.308421494738, 11583.826242606536, 11631.302121872715, 11835.881641183478, 10554.841677374692, 10753.354798712102, 11132.998030177323, 11171.38557379352, 11804.996575291196, 11526.942365288456, 11616.477258523555, 11840.585579504675, 12032.879671203176, 12110.394480276127, 7372.668229929559, 8198.467099638505, 8215.753527393948, 8260.958695206726, 8495.787605309917, 8468.0, 11179.776404472028, 11497.310161390344, 11815.0, 11862.40687965581], "value": 9482.925040612172, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1554.5802633289015, 1576.1725094325477, 1815.4735126813225, 1901.106479954421, 1970.7930667279927, 8485.91514084859, 8982.19160275575, 8991.955040224799, 8997.640094396216, 8998.370114092007, 7757.456978011542, 8065.717699880491, 8074.434789564718, 8133.552654603995, 8163.0, 1984.2162345873444, 2004.9398518044409, 2112.091800525777, 2353.4587044979667, 2363.1728894886824, 2342.9414264643433, 2350.5063936573342, 2373.074500944633, 2376.370261880603, 2378.15575470708, 2047.9795202048012, 2370.63255195445, 2377.09670325277, 2379.7501262367464, 2385.7614238576175, 2176.5646870625937, 2211.9824880554966, 2317.629179331307, 2361.2562042956415, 2364.598018336887, 2330.8368414211013, 2333.195047708538, 2335.322756400642, 2346.97653023471, 2363.9054437822465, 8746.86879696805, 8764.1674040966, 8828.24959878411, 8833.205011548927, 8851.911480885174, 5232.084385232569, 5340.652857564246, 5397.865053373658, 5400.324959380077, 5424.864378390533, 3518.243577630796, 3628.455731640249, 3666.6699997000396, 3681.3925702259094, 3689.409694448875, 1033.5968972100886, 1078.9676309710642, 1103.0568863621568, 1105.220819322384, 1105.8396532502823, 11736.061115110771, 11740.823887641693, 11751.647450576496, 11766.823497647541, 11818.527258909633, 4590.609798167126, 4657.790399432034, 4799.256115302133, 4850.272459131088, 4903.926441103386, 9081.68214112503, 9121.77195570116, 9136.406133601295, 9149.085091490797, 9152.496612686233, 5276.841694749163, 5284.497972692588, 5284.894302113936, 5290.894182116336, 5299.8410047698635, 4351.151525452569, 4757.26262429324, 4823.662343635911, 4946.802127914915, 4951.7276549789385, 3533.3286675531567, 3581.0, 3703.2408356287438, 3785.8674946377296, 3789.0, 1145.1583086431467, 1172.1736175996073, 1177.6349331707197, 1179.8525184351956, 1187.6140254417348, 852.522587351073, 872.8996165441056, 902.9729108126767, 907.8955920069144, 944.0182210501002, 5833.183354330397, 5958.255218097738, 6037.09443583466, 6053.213082299361, 6142.324344322121, 903.8011637439753, 961.6057416459219, 965.7344230336713, 988.3723835364596, 992.3252188511736, 3728.2729867675544, 3857.5370955485523, 3992.680585553149, 4005.6995725320717, 4101.482451492907, 9758.512074396253, 9946.756655418072, 10148.949255253825, 10149.98500149979, 10159.949200253952, 10038.196944244442, 10118.0, 10125.443100629389, 10129.0, 10150.238732095122, 8222.876656850152, 8332.875006874901, 8357.749267522106, 8358.874616880752, 8384.958075209706, 5759.65442073477, 5901.05583106718, 5904.940950590439, 6050.727717252822, 6234.376562343729, 9686.225101991964, 9816.705498835046, 10093.798124037625, 10094.54574544162, 10129.544170512492, 10317.587296508056, 10323.690289291333, 10325.122364599094, 10337.37975721429, 10353.378797272188, 7430.959665646706, 7474.327310542078, 7529.88705169412, 7607.4674772765375, 7619.580923049286, 8029.478083924526, 8145.551994640352, 8154.959225203836, 8281.751547453587, 8299.95850020746, 7321.816954576178, 7446.404287657083, 7475.02824632816, 7538.509996850187, 7604.201558836464, 7321.5607063574225, 7347.889781653173, 7348.338649521569, 7364.0, 7415.592142432218, 6899.448044156556, 6921.2386637470845, 6985.336393042653, 6996.965015174892, 7002.934134430458, 4987.12725273083, 4993.4756850530175, 5048.8990220194655, 5080.745962701776, 5151.587872970228, 4939.135651260943, 4974.079795237748, 5057.443681194992, 5209.4009188943755, 5231.476852314738], "value": 4560.905372646491, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.3296018039051, 305.136463807425, 305.7859498351154, 310.61328645835925, 312.49064025638205, 4183.803503160842, 5242.213667949804, 5259.395169555498, 5274.973625131869, 5344.0, 4655.557722016411, 5204.401493828211, 5248.317718696574, 5257.447967963366, 5304.443033481487, 3267.7875938064067, 3913.941290880639, 3985.9202815943663, 4001.539438105085, 4080.83676652935, 3995.6603688686496, 4029.2747305485, 4059.6143366380147, 4062.5328087270045, 4104.384342348657, 4000.6999475039343, 4000.739951903117, 4004.659603933669, 4036.9596304036877, 4039.6970227233073, 3840.0, 4013.5183777946686, 4041.50464328199, 4067.1865626874446, 4132.483439570054, 3144.937101257985, 3334.883279085245, 3518.4194607889667, 3580.4629305604108, 3591.407417776064, 5130.999455106218, 5200.373811225345, 5213.0, 5213.113770658997, 5219.269302297681, 3980.8009599520196, 4169.541350451448, 4328.394024836525, 4694.366260554815, 4738.502457242009, 4560.475545312298, 4930.482299358551, 5071.641686615704, 5572.0527510323345, 5586.301712285965, 3644.2143349758426, 3925.1953349563273, 3925.450436938831, 3948.2893079245723, 3954.9011274718073, 5111.642185047048, 5450.31871016119, 5847.678377689225, 5858.502027327682, 5881.411858814127, 3436.6563343665935, 3802.650059228934, 3960.32674445345, 3986.800659967048, 4013.6989725770686, 3480.7027361940995, 4035.858744943913, 4040.070783719761, 4044.919101617951, 4076.2458945095254, 3930.312195365857, 4300.752781693336, 4640.396748422683, 4701.743163281576, 4704.388429504211, 5447.1829225616475, 5458.744488767606, 5482.5613950883835, 5667.489925906688, 5737.08206686928, 1334.8665133486768, 1359.7484465373936, 1372.8901687864948, 1386.2167875150508, 1392.693607406369, 808.1756608259542, 813.9918600814035, 829.4940086547201, 854.4275335525213, 864.3085531574715, 4283.5502272260965, 5110.170888510396, 5139.871503212375, 5179.663321884065, 5298.708571028554, 5081.00920320534, 5260.526552610207, 5399.2980912481125, 5658.292713410824, 5759.107338362561, 4908.735690765601, 5019.824306149268, 5244.265802787613, 5253.789848406021, 5296.629235953446, 2869.708631116006, 2979.716926891942, 2992.102369289248, 2995.9400811984074, 2999.5853338062634, 5219.817306394334, 5908.586398952118, 5917.497012753878, 5963.522918166536, 5973.850653733692, 5004.824831130893, 5012.523810238022, 5380.551848908815, 5852.0, 5887.61730487517, 5010.674306170087, 5058.974705126451, 5509.724513774296, 5545.4731800478085, 6026.969865150646, 4669.346291519258, 4846.006568653488, 5449.3460784706085, 5779.739911704068, 5795.594308398372, 5672.858178545404, 5685.260916080883, 5695.572832037531, 5703.144528320785, 5771.480566749013, 5715.713714514061, 5749.981259838618, 5775.267419773967, 6064.3935606439, 6083.330833608211, 3406.6423025581958, 3646.741874053447, 3677.3196958562753, 3816.3512202925012, 3828.138668799609, 4418.668599855088, 5155.2782610435315, 5692.914606280992, 5704.3725190228215, 5713.428657134254, 4930.383702037246, 5216.269722238965, 5594.524465420485, 5691.743871525874, 5704.80033198836, 5226.372835259793, 5408.0, 5603.411641777718, 5639.746211420414, 5658.453695836807, 4676.906461870812, 5556.110922286442, 5570.888582228414, 5593.552515798645, 5608.074667679787, 4746.691465054761, 5315.495027972336, 5512.6692398456225, 5515.227868098389, 5518.365387980273, 4632.475300960917, 5461.808836690697, 5493.917591236055, 5541.972290138524, 5560.054790685674], "value": 4066.5485043385634, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.8758092172026, 699.176218119246, 704.8519810839723, 753.5666991479899, 799.3485309472779, 14950.925245373772, 15118.017328873615, 15687.843121568781, 15725.764113538306, 16173.19134043299, 14420.423183072664, 14455.710885782279, 14623.926880365583, 14661.486847960323, 14678.926605366958, 879.2306731609841, 911.3939230411768, 911.4667919267237, 912.8083102548472, 913.5477938420491, 717.6842189436658, 742.2317900972495, 748.0611832150671, 901.2204443156655, 906.0758026812647, 925.6019911438079, 956.0391806234725, 962.7063745557602, 964.3056998960772, 964.7250533597926, 940.1867384712209, 943.6838659049226, 945.2249155692335, 945.3382632157449, 949.126803340928, 558.0568838662647, 578.4504720515528, 700.3626699703245, 701.5159539917491, 703.0192880931072, 14039.438422463089, 14199.07705999117, 14275.928620356832, 14844.109353438827, 14909.925450372788, 14239.572812815632, 14591.854081459156, 14696.853031469655, 14851.405943762236, 14904.403823847033, 11723.355215463145, 11837.526498940031, 11850.229735067192, 11982.0, 12093.57672481459, 3551.254236610315, 3597.0, 3740.1584643455276, 3785.0726571989762, 3807.6192380761704, 104288.95711042869, 106100.46949765201, 106108.0, 108023.45988270166, 110341.0, 9666.661666841626, 11231.045361144232, 11453.141014423785, 11495.712607184887, 11542.13433992454, 20863.1654733812, 23306.65040024401, 23310.417239569146, 23323.533529329317, 23327.18354857572, 7756.418268629763, 8124.146964568635, 8222.013358397031, 8226.218509241613, 8233.46482478637, 4369.9126017479475, 4401.295792673221, 4523.909521809545, 4595.0, 4596.37948876904, 3515.1036485695986, 3639.381305178073, 3716.47969284295, 3728.9440658390145, 3732.384156614128, 640.8654182621608, 648.3807963394946, 658.3317932298721, 660.986780264392, 671.8723442545901, 604.7157835817159, 609.969501524922, 613.5705006495471, 644.6687590126375, 659.3538332434146, 8833.867491987625, 9352.307882687786, 9532.52337383128, 9654.972455784224, 9687.9031209687, 582.5397935630889, 617.4689766800548, 654.1365397675119, 670.9563878347892, 671.3923898871432, 4297.312430011183, 4621.2837010263465, 4625.7687115644085, 4654.464736555272, 4716.716996980193, 95430.0, 108219.0, 121944.0, 126343.0, 130068.48972653989, 79346.6032669833, 89996.0, 107133.39299910508, 108886.0, 124473.37763311306, 74194.0, 90875.54562227147, 111836.88163118427, 113242.0, 113399.99999999836, 9442.205980863613, 9602.567884445078, 9758.77960273094, 9831.402206341463, 9950.502474876082, 58261.0, 60530.4867378319, 60775.392246076975, 62527.6873615629, 64473.67763161154, 85810.0, 92434.44569428821, 93556.53221733848, 93957.1208575811, 94663.58004629798, 12902.741945161231, 12953.664969759913, 13075.41160647754, 13150.079494435298, 13284.07011509185, 14409.42362305538, 14411.42354305816, 14448.060876043024, 14895.106293622417, 14898.553043408716, 13182.802257965946, 13266.602001939957, 13485.50595415001, 13641.522546710818, 13850.238236897067, 13540.458381664623, 13646.727065458834, 13765.0, 13793.724125517232, 13842.792358114435, 13200.933995329962, 13389.66525836862, 13411.075803905234, 13416.0, 13629.727405452033, 10652.680419587425, 10906.781864362509, 11231.66305010851, 11359.716007099889, 11449.828252576051, 10479.738006549593, 11185.264681658353, 11495.885041149815, 11579.189456738284, 11762.76474470523], "value": 7751.503070272836, "unit": "score"}], "name": "dromaeo_css", "value": 7309.155173624841, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 111.0, 111.0, 112.0, 113.0, 112.0, 112.0, 112.0, 112.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 114.0, 148.0, 110.0, 114.0, 113.0, 114.0, 146.0, 112.0], "value": 123.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 185.0, 185.0, 183.0, 185.0, 178.0, 184.0, 182.0, 185.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 58.0, 59.0, 86.0, 61.0, 59.0, 59.0, 57.0, 80.0, 59.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 70.0, 95.0, 71.0, 68.0, 69.0, 92.0, 67.0, 69.0, 90.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 90.0, 86.0, 73.0, 114.0, 134.0, 108.0, 88.0, 85.0], "value": 95.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 97.0, 99.0, 143.0, 97.0, 96.0, 97.0, 96.0, 97.0, 96.0], "value": 102.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 83.0, 80.0, 80.0, 82.0, 83.0, 82.0, 81.0, 81.0], "value": 81.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 52.0, 52.0, 54.0, 55.0, 54.0, 56.0, 55.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 66.0, 75.0, 70.0, 68.0, 68.0, 70.0, 69.0, 71.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 108.0, 107.0, 72.0, 100.0, 105.0, 107.0, 106.0, 87.0, 90.0], "value": 98.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 145.0, 146.0, 146.0, 145.0, 146.0, 149.0, 157.0, 215.0, 142.0], "value": 153.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 173.0, 173.0, 171.0, 171.0, 189.0, 240.0, 199.0, 171.0, 171.0], "value": 183.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 69.0, 70.0, 68.0, 68.0, 67.0, 68.0, 66.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1469.6, "alertThreshold": 2.0}]} 09:00:56 INFO - Return code: 0 09:00:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:00:56 INFO - # TBPL SUCCESS # 09:00:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:00:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:00:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:00:56 INFO - 2016-10-17 09:00:56.735 screenresolution[1666:9756] starting screenresolution argv=screenresolution get 09:00:56 INFO - 2016-10-17 09:00:56.738 screenresolution[1666:9756] Display 0: 1600x1200x32@60 09:00:56 INFO - 2016-10-17 09:00:56.744 screenresolution[1667:9759] starting screenresolution argv=screenresolution list 09:00:56 INFO - Available Modes on Display 0 09:00:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:00:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:00:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:00:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:00:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:00:56 INFO - Intel Iris: 09:00:56 INFO - Chipset Model: Intel Iris 09:00:56 INFO - Type: GPU 09:00:56 INFO - Bus: Built-In 09:00:56 INFO - VRAM (Dynamic, Max): 1536 MB 09:00:56 INFO - Vendor: Intel (0x8086) 09:00:56 INFO - Device ID: 0x0a2e 09:00:56 INFO - Revision ID: 0x0009 09:00:56 INFO - Displays: 09:00:56 INFO - Display: 09:00:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:00:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:00:56 INFO - Display Serial Number: 1600x1200 60 09:00:56 INFO - Main Display: Yes 09:00:56 INFO - Mirror: Off 09:00:56 INFO - Online: Yes 09:00:56 INFO - Rotation: Supported 09:00:56 INFO - Return code: 0 09:00:56 INFO - Running post-action listener: _resource_record_post_action 09:00:56 INFO - [mozharness: 2016-10-17 16:00:56.981521Z] Finished run-tests step (success) 09:00:56 INFO - Running post-run listener: _resource_record_post_run 09:00:57 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 37356544; Write bytes: 708636672; Read time: 238; Write time: 2020 09:00:57 INFO - TinderboxPrint: CPU usage
53.7% 09:00:57 INFO - TinderboxPrint: I/O read bytes / time
37,356,544 / 238 09:00:57 INFO - TinderboxPrint: I/O write bytes / time
708,636,672 / 2,020 09:00:57 INFO - TinderboxPrint: CPU idle
2,249.4 (46.3%) 09:00:57 INFO - TinderboxPrint: CPU system
976.7 (20.1%) 09:00:57 INFO - TinderboxPrint: CPU user
1,635.5 (33.6%) 09:00:57 INFO - TinderboxPrint: Swap in / out
444,321,792 / 0 09:00:57 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 391467520; Write bytes: 386119680; Read time: 29259; Write time: 826 09:00:57 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36402176; Write bytes: 298895360; Read time: 227; Write time: 1138 09:00:57 INFO - Running post-run listener: _upload_blobber_files 09:00:57 INFO - Blob upload gear active. 09:00:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:00: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 09:00: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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:00: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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:00:57 INFO - (blobuploader) - INFO - Open directory for files ... 09:00:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:00:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:00:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:00:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:00:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:00:59 INFO - (blobuploader) - INFO - Done attempting. 09:00:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:00:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:00:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:01:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:01:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:01:00 INFO - (blobuploader) - INFO - Done attempting. 09:01:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:01:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:01:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:01:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:01:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:01:02 INFO - (blobuploader) - INFO - Done attempting. 09:01:02 INFO - (blobuploader) - INFO - Iteration through files over. 09:01:02 INFO - Return code: 0 09:01:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:01:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:01:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/079248612947de362cd184fab2cd2769a4013e499f027b5e126a97aac2fdb6486a99d33b66b1176bd07640902c3d4aadcc5254915457fbc886a8ea7711e80b63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9e8d2c00d451d6df88b8a8293266574c9aebad22a2b7d6278a40808a474354b495768a6d36cab79321059c2cf471913eb9eefbbe31c54421d23074e60b33041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76bcb4c9454063461c6ff68336f34dfa1a11712c99060e308e7fca9bf94ab72e95505b0450d17d3efd4e8040196643eb1100deee7793791c7346f85618579fb1"} 09:01:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:01:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:01:02 INFO - Contents: 09:01:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/079248612947de362cd184fab2cd2769a4013e499f027b5e126a97aac2fdb6486a99d33b66b1176bd07640902c3d4aadcc5254915457fbc886a8ea7711e80b63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9e8d2c00d451d6df88b8a8293266574c9aebad22a2b7d6278a40808a474354b495768a6d36cab79321059c2cf471913eb9eefbbe31c54421d23074e60b33041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76bcb4c9454063461c6ff68336f34dfa1a11712c99060e308e7fca9bf94ab72e95505b0450d17d3efd4e8040196643eb1100deee7793791c7346f85618579fb1"} 09:01:02 INFO - Running post-run listener: copy_logs_to_upload_dir 09:01:02 INFO - Copying logs to upload dir... 09:01:02 INFO - mkdir: /builds/slave/test/build/upload/logs 09:01:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.219223 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-17 09:01:02.797483) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 09:01:02.800657) ========= 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.oGr6JJNxuA/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.zMNVqBOSyU/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/079248612947de362cd184fab2cd2769a4013e499f027b5e126a97aac2fdb6486a99d33b66b1176bd07640902c3d4aadcc5254915457fbc886a8ea7711e80b63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9e8d2c00d451d6df88b8a8293266574c9aebad22a2b7d6278a40808a474354b495768a6d36cab79321059c2cf471913eb9eefbbe31c54421d23074e60b33041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76bcb4c9454063461c6ff68336f34dfa1a11712c99060e308e7fca9bf94ab72e95505b0450d17d3efd4e8040196643eb1100deee7793791c7346f85618579fb1"} build_url:https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011095 build_url: 'https://queue.taskcluster.net/v1/task/MAILgq7BQPi-yU-AC_Mr2A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/079248612947de362cd184fab2cd2769a4013e499f027b5e126a97aac2fdb6486a99d33b66b1176bd07640902c3d4aadcc5254915457fbc886a8ea7711e80b63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9e8d2c00d451d6df88b8a8293266574c9aebad22a2b7d6278a40808a474354b495768a6d36cab79321059c2cf471913eb9eefbbe31c54421d23074e60b33041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76bcb4c9454063461c6ff68336f34dfa1a11712c99060e308e7fca9bf94ab72e95505b0450d17d3efd4e8040196643eb1100deee7793791c7346f85618579fb1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 09:01:02.833986) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 09:01:02.834343) ========= 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.oGr6JJNxuA/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.zMNVqBOSyU/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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.005042 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 09:01:02.883854) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 09:01:02.884170) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 09:01:02.884579) ========= ========= Total master_lag: 0.17 =========