builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0300 starttime: 1478190822.93 results: success (0) buildid: 20161103030205 builduid: 1d1483cf12d64dbbac00c1ce9f64e2ad revision: ade8d4a63e57560410de106450f37b50ed71cca5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-03 09:33:42.930828) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-03 09:33:42.931251) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-03 09:33:42.931542) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-03 09:33:42.970561) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 09:33:42.970865) ========= 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.8aavnU58dK/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.7TR786NQ00/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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-11-03 09:33:42-- 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% 58.7M=0s 2016-11-03 09:33:43 (58.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.088401 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 09:33:43.083456) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 09:33:43.083801) ========= 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.8aavnU58dK/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.7TR786NQ00/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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.021239 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 09:33:43.139723) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-03 09:33:43.140069) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ade8d4a63e57560410de106450f37b50ed71cca5 --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 ade8d4a63e57560410de106450f37b50ed71cca5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8aavnU58dK/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.7TR786NQ00/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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-11-03 09:33:43,228 truncating revision to first 12 chars 2016-11-03 09:33:43,228 Setting DEBUG logging. 2016-11-03 09:33:43,228 attempt 1/10 2016-11-03 09:33:43,228 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ade8d4a63e57?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-03 09:33:43,807 unpacking tar archive at: mozilla-central-ade8d4a63e57/testing/mozharness/ program finished with exit code 0 elapsedTime=0.849781 ========= master_lag: 1.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-03 09:33:45.196716) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-03 09:33:45.197090) ========= script_repo_revision: ade8d4a63e57560410de106450f37b50ed71cca5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-03 09:33:45.200561) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-03 09:33:45.200838) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-03 09:33:45.236862) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-03 09:33:45.237129) ========= /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.8aavnU58dK/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.7TR786NQ00/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:33:45 INFO - MultiFileLogger online at 20161103 09:33:45 in /builds/slave/test 09:33:45 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 09:33:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:33:45 INFO - {'append_to_log': False, 09:33:45 INFO - 'base_work_dir': '/builds/slave/test', 09:33:45 INFO - 'blob_upload_branch': 'Firefox', 09:33:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:33:45 INFO - 'branch': 'Firefox', 09:33:45 INFO - 'buildbot_json_path': 'buildprops.json', 09:33:45 INFO - 'config_files': ('talos/mac_config.py',), 09:33:45 INFO - 'default_actions': ('clobber', 09:33:45 INFO - 'read-buildbot-config', 09:33:45 INFO - 'download-and-extract', 09:33:45 INFO - 'populate-webroot', 09:33:45 INFO - 'create-virtualenv', 09:33:45 INFO - 'install', 09:33:45 INFO - 'run-tests'), 09:33:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:33:45 INFO - 'download_minidump_stackwalk': True, 09:33:45 INFO - 'download_symbols': 'ondemand', 09:33:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:33:45 INFO - 'tooltool.py': '/tools/tooltool.py', 09:33:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:33:45 INFO - '/tools/misc-python/virtualenv.py')}, 09:33:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:33:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:33:45 INFO - 'installer_path': 'installer.exe', 09:33:45 INFO - 'log_level': 'info', 09:33:45 INFO - 'log_name': 'talos', 09:33:45 INFO - 'log_to_console': True, 09:33:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:33:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:33:45 INFO - 'opt_config_files': (), 09:33:45 INFO - 'pip_index': False, 09:33:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:33:45 INFO - 'cmd': ('bash', 09:33:45 INFO - '-c', 09:33:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:33:45 INFO - 'enabled': True, 09:33:45 INFO - 'halt_on_failure': False, 09:33:45 INFO - 'name': 'check_screen_resolution'},), 09:33:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:33:45 INFO - 'cmd': ('bash', 09:33:45 INFO - '-c', 09:33:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:33:45 INFO - 'enabled': True, 09:33:45 INFO - 'halt_on_failure': False, 09:33:45 INFO - 'name': 'check_screen_resolution'},), 09:33:45 INFO - 'run_cmd_checks_enabled': True, 09:33:45 INFO - 'sps_profile': False, 09:33:45 INFO - 'sps_profile_interval': 0, 09:33:45 INFO - 'suite': 'dromaeojs', 09:33:45 INFO - 'system_bits': '32', 09:33:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:33:45 INFO - 'title': 't-yosemite-r7-0300', 09:33:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:33:45 INFO - 'use_talos_json': True, 09:33:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:33:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:33:45 INFO - 'work_dir': 'build'} 09:33:45 INFO - [mozharness: 2016-11-03 16:33:45.444718Z] Running clobber step. 09:33:45 INFO - Running pre-action listener: _resource_record_pre_action 09:33:45 INFO - Running main action method: clobber 09:33:45 INFO - rmtree: /builds/slave/test/build 09:33:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:33:47 INFO - Running post-action listener: _resource_record_post_action 09:33:47 INFO - [mozharness: 2016-11-03 16:33:47.136157Z] Finished clobber step (success) 09:33:47 INFO - [mozharness: 2016-11-03 16:33:47.136574Z] Running read-buildbot-config step. 09:33:47 INFO - Running pre-action listener: _resource_record_pre_action 09:33:47 INFO - Running main action method: read_buildbot_config 09:33:47 INFO - Using buildbot properties: 09:33:47 INFO - { 09:33:47 INFO - "project": "", 09:33:47 INFO - "product": "firefox", 09:33:47 INFO - "script_repo_revision": "production", 09:33:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:33:47 INFO - "repository": "", 09:33:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:33:47 INFO - "buildid": "20161103030205", 09:33:47 INFO - "stage_platform": "macosx64", 09:33:47 INFO - "basedir": "/builds/slave/test", 09:33:47 INFO - "nightly_build": "True", 09:33:47 INFO - "buildnumber": 20, 09:33:47 INFO - "slavename": "t-yosemite-r7-0300", 09:33:47 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:33:47 INFO - "platform": "yosemite_r7", 09:33:47 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:33:47 INFO - "branch": "mozilla-central", 09:33:47 INFO - "repo_path": "mozilla-central", 09:33:47 INFO - "revision": "ade8d4a63e57560410de106450f37b50ed71cca5", 09:33:47 INFO - "pgo_build": "False", 09:33:47 INFO - "builduid": "1d1483cf12d64dbbac00c1ce9f64e2ad", 09:33:47 INFO - "slavebuilddir": "test" 09:33:47 INFO - } 09:33:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 09:33:47 INFO - Running post-action listener: _resource_record_post_action 09:33:47 INFO - [mozharness: 2016-11-03 16:33:47.143396Z] Finished read-buildbot-config step (success) 09:33:47 INFO - [mozharness: 2016-11-03 16:33:47.143621Z] Running download-and-extract step. 09:33:47 INFO - Running pre-action listener: _resource_record_pre_action 09:33:47 INFO - Running main action method: download_and_extract 09:33:47 INFO - mkdir: /builds/slave/test/build/tests 09:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:47 INFO - trying https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 09:33:47 INFO - Downloading https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/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 09:33:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/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 09:33:48 INFO - Downloaded 1199 bytes. 09:33:48 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:33:48 INFO - Using the following test package requirements: 09:33:48 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:33:48 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:33:48 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 09:33:48 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:33:48 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 09:33:48 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:33:48 INFO - u'jsshell-mac.zip'], 09:33:48 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:33:48 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 09:33:48 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:33:48 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:33:48 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 09:33:48 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:33:48 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 09:33:48 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:33:48 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 09:33:48 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:33:48 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 09:33:48 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:33:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:33:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:33:48 INFO - Fetch https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:33:49 INFO - Http code: 200 09:33:49 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 09:33:49 INFO - x-amz-cf-id: WWRW1TivW0PY0sZWUtcbtS3FgGlGBC0N4zPz0DOzY3lErUVn8zEYqg== 09:33:49 INFO - x-amz-version-id: eKq6BuUe4f_gOcSYdb9daBuctkYheATO 09:33:49 INFO - Expected file size: 16389309 09:33:49 INFO - Obtained file size: 16389309 09:33:56 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 09:33:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:33:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:33:56 INFO - Fetch https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:33:56 INFO - Http code: 200 09:33:56 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 09:33:56 INFO - x-amz-cf-id: pXbScDnbiQBXfDoKOa7djZMws6vewy3dMQjNGQ38_x8SIUKMlmQpGQ== 09:33:56 INFO - x-amz-version-id: eKq6BuUe4f_gOcSYdb9daBuctkYheATO 09:33:56 INFO - Expected file size: 16389309 09:33:56 INFO - Obtained file size: 16389309 09:34:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 09:34:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:34:03 INFO - Fetch https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 09:34:03 INFO - Http code: 200 09:34:03 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 09:34:03 INFO - x-amz-cf-id: 18QpgX7Tu4KAQTa52_F1OI11uhYij3tU0OW2lMyMi98mYJ355yjsQQ== 09:34:03 INFO - x-amz-version-id: WlTrlaMySozm31t3ZvC4rjaqy1F8ZvtY 09:34:04 INFO - Expected file size: 14409038 09:34:04 INFO - Obtained file size: 14409038 09:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:04 INFO - trying https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:34:04 INFO - Downloading https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 09:34:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/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 09:34:07 INFO - Downloaded 112093920 bytes. 09:34:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:34:07 INFO - mkdir: /builds/slave/test/properties 09:34:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:34:07 INFO - Writing to file /builds/slave/test/properties/build_url 09:34:07 INFO - Contents: 09:34:07 INFO - build_url:https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:34:07 INFO - Running post-action listener: _resource_record_post_action 09:34:07 INFO - Running post-action listener: set_extra_try_arguments 09:34:08 INFO - [mozharness: 2016-11-03 16:34:08.000016Z] Finished download-and-extract step (success) 09:34:08 INFO - [mozharness: 2016-11-03 16:34:08.000141Z] Running populate-webroot step. 09:34:08 INFO - Running pre-action listener: _resource_record_pre_action 09:34:08 INFO - Running main action method: populate_webroot 09:34:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:34:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:34:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:34:08 INFO - '--tppagecycles', 09:34:08 INFO - '7'], 09:34:08 INFO - 'tests': ['tsvgm']}}, 09:34:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:34:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:34:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:34:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:34:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:34:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:34:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:08 INFO - 'talos_options': ['--disable-e10s'], 09:34:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:34:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:34:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:08 INFO - 'talos_options': ['--disable-e10s'], 09:34:08 INFO - 'tests': ['damp', 'tps']}, 09:34:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:08 INFO - 'tests': ['damp', 'tps']}, 09:34:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:34:08 INFO - 'tests': ['dromaeo_dom']}, 09:34:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:34:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:34:08 INFO - 'tests': ['basic_compositor_video']}, 09:34:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:34:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:34:08 INFO - 'tests': ['a11yr', 09:34:08 INFO - 'ts_paint', 09:34:08 INFO - 'tpaint', 09:34:08 INFO - 'sessionrestore', 09:34:08 INFO - 'sessionrestore_no_auto_restore', 09:34:08 INFO - 'tabpaint']}, 09:34:08 INFO - 'other-e10s': {'tests': ['a11yr', 09:34:08 INFO - 'ts_paint', 09:34:08 INFO - 'tpaint', 09:34:08 INFO - 'sessionrestore', 09:34:08 INFO - 'sessionrestore_no_auto_restore', 09:34:08 INFO - 'tabpaint']}, 09:34:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:34:08 INFO - 'ts_paint', 09:34:08 INFO - 'tpaint', 09:34:08 INFO - 'sessionrestore', 09:34:08 INFO - 'sessionrestore_no_auto_restore', 09:34:08 INFO - 'tabpaint']}, 09:34:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:34:08 INFO - 'ts_paint', 09:34:08 INFO - 'tpaint', 09:34:08 INFO - 'sessionrestore', 09:34:08 INFO - 'sessionrestore_no_auto_restore', 09:34:08 INFO - 'tabpaint']}, 09:34:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:34:08 INFO - 'tests': ['a11yr', 09:34:08 INFO - 'ts_paint', 09:34:08 INFO - 'tpaint', 09:34:08 INFO - 'sessionrestore', 09:34:08 INFO - 'sessionrestore_no_auto_restore', 09:34:08 INFO - 'tabpaint']}, 09:34:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:34:08 INFO - 'tests': ['a11yr', 09:34:08 INFO - 'ts_paint', 09:34:08 INFO - 'tpaint', 09:34:08 INFO - 'sessionrestore', 09:34:08 INFO - 'sessionrestore_no_auto_restore', 09:34:08 INFO - 'tabpaint']}, 09:34:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:34:08 INFO - 'tests': ['tsvgx', 09:34:08 INFO - 'tsvgr_opacity', 09:34:08 INFO - 'tart', 09:34:08 INFO - 'tscrollx', 09:34:08 INFO - 'cart']}, 09:34:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:34:08 INFO - 'tsvgr_opacity', 09:34:08 INFO - 'tart', 09:34:08 INFO - 'tscrollx', 09:34:08 INFO - 'cart']}, 09:34:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:08 INFO - 'talos_options': ['--disable-e10s'], 09:34:08 INFO - 'tests': ['tp5o']}, 09:34:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:08 INFO - 'tests': ['tp5o']}, 09:34:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:08 INFO - 'talos_options': ['--disable-e10s', 09:34:08 INFO - '--xperf_path', 09:34:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:34:08 INFO - 'tests': ['tp5n']}, 09:34:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:08 INFO - 'talos_options': ['--xperf_path', 09:34:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:34:08 INFO - 'tests': ['tp5n']}}, 09:34:08 INFO - 'talos.zip': {'path': '', 09:34:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:34:08 INFO - Running post-action listener: _resource_record_post_action 09:34:08 INFO - [mozharness: 2016-11-03 16:34:08.018997Z] Finished populate-webroot step (success) 09:34:08 INFO - [mozharness: 2016-11-03 16:34:08.019105Z] Running create-virtualenv step. 09:34:08 INFO - Running pre-action listener: _resource_record_pre_action 09:34:08 INFO - Running main action method: create_virtualenv 09:34:08 INFO - Creating virtualenv /builds/slave/test/build/venv 09:34:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:34:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:34:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:34:08 INFO - Using real prefix '/tools/python27' 09:34:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:34:08 INFO - Installing distribute.............................................................................................................................................................................................done. 09:34:11 INFO - Installing pip.................done. 09:34:11 INFO - Return code: 0 09:34:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:34:11 INFO - Reading from file tmpfile_stdout 09:34:11 INFO - Output received: 09:34:11 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) 09:34:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:34:11 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')]} 09:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:11 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')]} 09:34:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:11 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 0x100d5a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d256c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6ed30>, '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 0x100994db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b66b20>, '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.8aavnU58dK/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.7TR786NQ00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:34:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:34:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:34:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8aavnU58dK/Render', 09:34:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:12 INFO - 'HOME': '/Users/cltbld', 09:34:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:12 INFO - 'LOGNAME': 'cltbld', 09:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:12 INFO - 'MOZ_NO_REMOTE': '1', 09:34:12 INFO - 'NO_EM_RESTART': '1', 09:34:12 INFO - 'PAGER': '/bin/cat', 09:34:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:12 INFO - 'PWD': '/builds/slave/test', 09:34:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:12 INFO - 'SHELL': '/bin/bash', 09:34:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TR786NQ00/Listeners', 09:34:12 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:34:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:12 INFO - 'USER': 'cltbld', 09:34:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:12 INFO - 'XPC_FLAGS': '0x0', 09:34:12 INFO - 'XPC_SERVICE_NAME': '0', 09:34:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:12 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 09:34:12 INFO - Cleaning up... 09:34:12 INFO - Return code: 0 09:34:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100d5a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d256c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6ed30>, '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 0x100994db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b66b20>, '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.8aavnU58dK/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.7TR786NQ00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:34:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:34:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:34:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8aavnU58dK/Render', 09:34:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:12 INFO - 'HOME': '/Users/cltbld', 09:34:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:12 INFO - 'LOGNAME': 'cltbld', 09:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:12 INFO - 'MOZ_NO_REMOTE': '1', 09:34:12 INFO - 'NO_EM_RESTART': '1', 09:34:12 INFO - 'PAGER': '/bin/cat', 09:34:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:12 INFO - 'PWD': '/builds/slave/test', 09:34:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:12 INFO - 'SHELL': '/bin/bash', 09:34:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TR786NQ00/Listeners', 09:34:12 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:34:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:12 INFO - 'USER': 'cltbld', 09:34:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:12 INFO - 'XPC_FLAGS': '0x0', 09:34:12 INFO - 'XPC_SERVICE_NAME': '0', 09:34:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:12 INFO - Downloading/unpacking psutil>=3.1.1 09:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:34:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:34:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:34:15 INFO - Installing collected packages: psutil 09:34:15 INFO - Running setup.py install for psutil 09:34:16 INFO - building 'psutil._psutil_osx' extension 09:34:16 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 09:34:16 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 09:34:16 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 09:34:16 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 09:34:16 INFO - building 'psutil._psutil_posix' extension 09:34:16 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 09:34:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:34:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:34:16 INFO - ^ 09:34:16 INFO - 1 warning generated. 09:34:16 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 09:34:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:34:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:34:16 INFO - Successfully installed psutil 09:34:16 INFO - Cleaning up... 09:34:16 INFO - Return code: 0 09:34:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:34:16 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')]} 09:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:16 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')]} 09:34:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:16 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 0x100d5a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d256c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6ed30>, '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 0x100994db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b66b20>, '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.8aavnU58dK/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.7TR786NQ00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:34:16 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 09:34:16 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 09:34:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8aavnU58dK/Render', 09:34:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:16 INFO - 'HOME': '/Users/cltbld', 09:34:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:16 INFO - 'LOGNAME': 'cltbld', 09:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:16 INFO - 'MOZ_NO_REMOTE': '1', 09:34:16 INFO - 'NO_EM_RESTART': '1', 09:34:16 INFO - 'PAGER': '/bin/cat', 09:34:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:16 INFO - 'PWD': '/builds/slave/test', 09:34:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:16 INFO - 'SHELL': '/bin/bash', 09:34:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TR786NQ00/Listeners', 09:34:16 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:34:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:16 INFO - 'USER': 'cltbld', 09:34:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:16 INFO - 'XPC_FLAGS': '0x0', 09:34:16 INFO - 'XPC_SERVICE_NAME': '0', 09:34:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:34:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:34:19 INFO - Installing collected packages: mozsystemmonitor 09:34:19 INFO - Running setup.py install for mozsystemmonitor 09:34:19 INFO - Successfully installed mozsystemmonitor 09:34:19 INFO - Cleaning up... 09:34:19 INFO - Return code: 0 09:34:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:19 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 0x100d5a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d256c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6ed30>, '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 0x100994db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b66b20>, '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.8aavnU58dK/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.7TR786NQ00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:34:19 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 09:34:19 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 09:34:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8aavnU58dK/Render', 09:34:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:19 INFO - 'HOME': '/Users/cltbld', 09:34:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:19 INFO - 'LOGNAME': 'cltbld', 09:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:19 INFO - 'MOZ_NO_REMOTE': '1', 09:34:19 INFO - 'NO_EM_RESTART': '1', 09:34:19 INFO - 'PAGER': '/bin/cat', 09:34:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:19 INFO - 'PWD': '/builds/slave/test', 09:34:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:19 INFO - 'SHELL': '/bin/bash', 09:34:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TR786NQ00/Listeners', 09:34:19 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:34:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:19 INFO - 'USER': 'cltbld', 09:34:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:19 INFO - 'XPC_FLAGS': '0x0', 09:34:19 INFO - 'XPC_SERVICE_NAME': '0', 09:34:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:20 INFO - Downloading/unpacking jsonschema==2.5.1 09:34:20 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 09:34:20 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 09:34:20 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 09:34:20 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 09:34:20 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 09:34:20 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 09:34:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:34:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:34:22 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 09:34:22 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 09:34:22 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 09:34:22 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 09:34:22 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 09:34:22 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 09:34:23 INFO - Downloading functools32-3.2.3-2.tar.gz 09:34:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:34:23 INFO - warning: no files found matching '*.txt' 09:34:23 INFO - no previously-included directories found matching 'build' 09:34:23 INFO - no previously-included directories found matching 'dist' 09:34:23 INFO - no previously-included directories found matching '.git*' 09:34:23 INFO - Installing collected packages: jsonschema, functools32 09:34:23 INFO - Running setup.py install for functools32 09:34:23 INFO - warning: no files found matching '*.txt' 09:34:23 INFO - no previously-included directories found matching 'build' 09:34:23 INFO - no previously-included directories found matching 'dist' 09:34:23 INFO - no previously-included directories found matching '.git*' 09:34:23 INFO - Successfully installed jsonschema functools32 09:34:23 INFO - Cleaning up... 09:34:23 INFO - Return code: 0 09:34:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:23 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 0x100d5a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d256c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6ed30>, '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 0x100994db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b66b20>, '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.8aavnU58dK/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.7TR786NQ00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:34:23 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 09:34:23 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 09:34:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8aavnU58dK/Render', 09:34:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:23 INFO - 'HOME': '/Users/cltbld', 09:34:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:23 INFO - 'LOGNAME': 'cltbld', 09:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:23 INFO - 'MOZ_NO_REMOTE': '1', 09:34:23 INFO - 'NO_EM_RESTART': '1', 09:34:23 INFO - 'PAGER': '/bin/cat', 09:34:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:23 INFO - 'PWD': '/builds/slave/test', 09:34:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:23 INFO - 'SHELL': '/bin/bash', 09:34:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TR786NQ00/Listeners', 09:34:23 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:34:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:23 INFO - 'USER': 'cltbld', 09:34:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:23 INFO - 'XPC_FLAGS': '0x0', 09:34:23 INFO - 'XPC_SERVICE_NAME': '0', 09:34:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:34:23 INFO - Cleaning up... 09:34:23 INFO - Return code: 0 09:34:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:23 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 0x100d5a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d256c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6ed30>, '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 0x100994db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b66b20>, '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.8aavnU58dK/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.7TR786NQ00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:34:23 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 09:34:23 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 09:34:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8aavnU58dK/Render', 09:34:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:23 INFO - 'HOME': '/Users/cltbld', 09:34:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:23 INFO - 'LOGNAME': 'cltbld', 09:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:23 INFO - 'MOZ_NO_REMOTE': '1', 09:34:23 INFO - 'NO_EM_RESTART': '1', 09:34:23 INFO - 'PAGER': '/bin/cat', 09:34:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:23 INFO - 'PWD': '/builds/slave/test', 09:34:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:23 INFO - 'SHELL': '/bin/bash', 09:34:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TR786NQ00/Listeners', 09:34:23 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:34:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:23 INFO - 'USER': 'cltbld', 09:34:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:23 INFO - 'XPC_FLAGS': '0x0', 09:34:23 INFO - 'XPC_SERVICE_NAME': '0', 09:34:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:23 INFO - Downloading/unpacking blobuploader==1.2.4 09:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:26 INFO - Downloading blobuploader-1.2.4.tar.gz 09:34:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:34:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:34:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:34:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:27 INFO - Downloading docopt-0.6.1.tar.gz 09:34:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:34:27 INFO - Installing collected packages: blobuploader, requests, docopt 09:34:27 INFO - Running setup.py install for blobuploader 09:34:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:34:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:34:27 INFO - Running setup.py install for requests 09:34:28 INFO - Running setup.py install for docopt 09:34:28 INFO - Successfully installed blobuploader requests docopt 09:34:28 INFO - Cleaning up... 09:34:28 INFO - Return code: 0 09:34:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:28 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')]} 09:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d5a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d256c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6ed30>, '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 0x100994db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b66b20>, '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.8aavnU58dK/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.7TR786NQ00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:34:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:34:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:34:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8aavnU58dK/Render', 09:34:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:29 INFO - 'HOME': '/Users/cltbld', 09:34:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:29 INFO - 'LOGNAME': 'cltbld', 09:34:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:29 INFO - 'MOZ_NO_REMOTE': '1', 09:34:29 INFO - 'NO_EM_RESTART': '1', 09:34:29 INFO - 'PAGER': '/bin/cat', 09:34:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:29 INFO - 'PWD': '/builds/slave/test', 09:34:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:29 INFO - 'SHELL': '/bin/bash', 09:34:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TR786NQ00/Listeners', 09:34:29 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:34:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:29 INFO - 'USER': 'cltbld', 09:34:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:29 INFO - 'XPC_FLAGS': '0x0', 09:34:29 INFO - 'XPC_SERVICE_NAME': '0', 09:34:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:29 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-FbfaS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:29 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-xNY_Bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:29 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-RhGBsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:30 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-GSbGKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:30 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-0_c_29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:30 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-sEje1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:30 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-ZsiQGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:30 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-g_yC5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:30 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-CwF0z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:30 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-SPbfv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:30 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-D1H5mZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:30 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-mGtSBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:30 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-V9XUit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:31 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-kdnCHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:31 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-3KHhaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:31 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-QMEw2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:31 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-Slny22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:34:31 INFO - Running setup.py install for manifestparser 09:34:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:34:31 INFO - Running setup.py install for mozcrash 09:34:31 INFO - Running setup.py install for mozdebug 09:34:31 INFO - Running setup.py install for mozdevice 09:34:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:34:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:34:32 INFO - Running setup.py install for mozfile 09:34:32 INFO - Running setup.py install for mozhttpd 09:34:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:34:32 INFO - Running setup.py install for mozinfo 09:34:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:34:32 INFO - Running setup.py install for mozInstall 09:34:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:34:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:34:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:34:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:34:32 INFO - Running setup.py install for mozleak 09:34:32 INFO - Running setup.py install for mozlog 09:34:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:34:33 INFO - Running setup.py install for moznetwork 09:34:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:34:33 INFO - Running setup.py install for mozprocess 09:34:33 INFO - Running setup.py install for mozprofile 09:34:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:34:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:34:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:34:33 INFO - Running setup.py install for mozrunner 09:34:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:34:33 INFO - Running setup.py install for mozscreenshot 09:34:33 INFO - Running setup.py install for moztest 09:34:33 INFO - Running setup.py install for mozversion 09:34:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:34:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:34:34 INFO - Cleaning up... 09:34:34 INFO - Return code: 0 09:34:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d5a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d256c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6ed30>, '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 0x100994db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b66b20>, '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.8aavnU58dK/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.7TR786NQ00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:34:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:34:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:34:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8aavnU58dK/Render', 09:34:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:34 INFO - 'HOME': '/Users/cltbld', 09:34:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:34 INFO - 'LOGNAME': 'cltbld', 09:34:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:34 INFO - 'MOZ_NO_REMOTE': '1', 09:34:34 INFO - 'NO_EM_RESTART': '1', 09:34:34 INFO - 'PAGER': '/bin/cat', 09:34:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:34 INFO - 'PWD': '/builds/slave/test', 09:34:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:34 INFO - 'SHELL': '/bin/bash', 09:34:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TR786NQ00/Listeners', 09:34:34 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:34:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:34 INFO - 'USER': 'cltbld', 09:34:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:34 INFO - 'XPC_FLAGS': '0x0', 09:34:34 INFO - 'XPC_SERVICE_NAME': '0', 09:34:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:34 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-sxwhx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:34 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)) 09:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:34 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-0pSmqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:34 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)) 09:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:34 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-mfEVRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34: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)) 09:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:35 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-ygfzCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34: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)) 09:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:35 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-_lBSR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34: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)) 09:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:35 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-iavLZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34: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)) 09:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:35 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-O7JOQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:35 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-_lPYCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:35 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-dP55A8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:35 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)) 09:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:35 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-sI8nME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:35 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)) 09:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:35 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-yXNCaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:35 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)) 09:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:35 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-yQHbbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34: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)) 09:34:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:36 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-HwgOVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34: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)) 09:34:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:36 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-xevJyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34: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)) 09:34:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:36 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-eTyo5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34: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)) 09:34:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:36 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-DmUOno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34: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)) 09:34:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:36 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-t9NHSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:34:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:34:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:34:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:34:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:34:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:34:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:34:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:39 INFO - Downloading blessings-1.6.tar.gz 09:34:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:34:39 INFO - Installing collected packages: blessings 09:34:39 INFO - Running setup.py install for blessings 09:34:39 INFO - Successfully installed blessings 09:34:39 INFO - Cleaning up... 09:34:39 INFO - Return code: 0 09:34:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:34:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:40 INFO - Reading from file tmpfile_stdout 09:34:40 INFO - Current package versions: 09:34:40 INFO - blessings == 1.6 09:34:40 INFO - blobuploader == 1.2.4 09:34:40 INFO - docopt == 0.6.1 09:34:40 INFO - functools32 == 3.2.3-2 09:34:40 INFO - jsonschema == 2.5.1 09:34:40 INFO - manifestparser == 1.1 09:34:40 INFO - mozInstall == 1.12 09:34:40 INFO - mozcrash == 0.17 09:34:40 INFO - mozdebug == 0.1 09:34:40 INFO - mozdevice == 0.48 09:34:40 INFO - mozfile == 1.2 09:34:40 INFO - mozhttpd == 0.7 09:34:40 INFO - mozinfo == 0.9 09:34:40 INFO - mozleak == 0.1 09:34:40 INFO - mozlog == 3.4 09:34:40 INFO - moznetwork == 0.27 09:34:40 INFO - mozprocess == 0.23 09:34:40 INFO - mozprofile == 0.28 09:34:40 INFO - mozrunner == 6.12 09:34:40 INFO - mozscreenshot == 0.1 09:34:40 INFO - mozsystemmonitor == 0.3 09:34:40 INFO - moztest == 0.7 09:34:40 INFO - mozversion == 1.4 09:34:40 INFO - psutil == 3.1.1 09:34:40 INFO - requests == 1.2.3 09:34:40 INFO - wsgiref == 0.1.2 09:34:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34: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')]} 09:34:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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')]} 09:34:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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 0x100d5a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d256c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6ed30>, '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 0x100994db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b66b20>, '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.8aavnU58dK/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.7TR786NQ00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:34: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 09:34: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 09:34:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8aavnU58dK/Render', 09:34:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:40 INFO - 'HOME': '/Users/cltbld', 09:34:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:40 INFO - 'LOGNAME': 'cltbld', 09:34:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:40 INFO - 'MOZ_NO_REMOTE': '1', 09:34:40 INFO - 'NO_EM_RESTART': '1', 09:34:40 INFO - 'PAGER': '/bin/cat', 09:34:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:40 INFO - 'PWD': '/builds/slave/test', 09:34:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:40 INFO - 'SHELL': '/bin/bash', 09:34:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TR786NQ00/Listeners', 09:34:40 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:34:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:40 INFO - 'USER': 'cltbld', 09:34:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:40 INFO - 'XPC_FLAGS': '0x0', 09:34:40 INFO - 'XPC_SERVICE_NAME': '0', 09:34:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34: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)) 09:34: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)) 09:34: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)) 09:34: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)) 09:34: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)) 09:34: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)) 09:34: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)) 09:34: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)) 09:34: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)) 09:34: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)) 09:34: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)) 09:34:40 INFO - Cleaning up... 09:34:40 INFO - Return code: 0 09:34:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:34: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')]} 09:34:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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')]} 09:34:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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 0x100d5a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d256c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b6ed30>, '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 0x100994db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b66b20>, '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.8aavnU58dK/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.7TR786NQ00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:34: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 09:34: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 09:34:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8aavnU58dK/Render', 09:34:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:40 INFO - 'HOME': '/Users/cltbld', 09:34:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:40 INFO - 'LOGNAME': 'cltbld', 09:34:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:40 INFO - 'MOZ_NO_REMOTE': '1', 09:34:40 INFO - 'NO_EM_RESTART': '1', 09:34:40 INFO - 'PAGER': '/bin/cat', 09:34:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:40 INFO - 'PWD': '/builds/slave/test', 09:34:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:40 INFO - 'SHELL': '/bin/bash', 09:34:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TR786NQ00/Listeners', 09:34:40 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:34:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:40 INFO - 'USER': 'cltbld', 09:34:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:40 INFO - 'XPC_FLAGS': '0x0', 09:34:40 INFO - 'XPC_SERVICE_NAME': '0', 09:34:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:34:40 INFO - Cleaning up... 09:34:40 INFO - Return code: 0 09:34:40 INFO - Running post-action listener: _resource_record_post_action 09:34:40 INFO - Running post-action listener: _start_resource_monitoring 09:34:40 INFO - Starting resource monitoring. 09:34:40 INFO - [mozharness: 2016-11-03 16:34:40.669212Z] Finished create-virtualenv step (success) 09:34:40 INFO - [mozharness: 2016-11-03 16:34:40.670207Z] Running install step. 09:34:40 INFO - Running pre-action listener: _resource_record_pre_action 09:34:40 INFO - Running main action method: install 09:34:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:40 INFO - Reading from file tmpfile_stdout 09:34:40 INFO - Detecting whether we're running mozinstall >=1.0... 09:34:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:34:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:34:41 INFO - Reading from file tmpfile_stdout 09:34:41 INFO - Output received: 09:34:41 INFO - Usage: mozinstall [options] installer 09:34:41 INFO - Options: 09:34:41 INFO - -h, --help show this help message and exit 09:34:41 INFO - -d DEST, --destination=DEST 09:34:41 INFO - Directory to install application into. [default: 09:34:41 INFO - "/builds/slave/test"] 09:34:41 INFO - --app=APP Application being installed. [default: firefox] 09:34:41 INFO - mkdir: /builds/slave/test/build/application 09:34: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'] 09:34: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 09:35:15 INFO - Reading from file tmpfile_stdout 09:35:15 INFO - Output received: 09:35:15 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 09:35:15 INFO - Running post-action listener: _resource_record_post_action 09:35:15 INFO - [mozharness: 2016-11-03 16:35:15.423311Z] Finished install step (success) 09:35:15 INFO - [mozharness: 2016-11-03 16:35:15.423520Z] Running run-tests step. 09:35:15 INFO - Running pre-action listener: _resource_record_pre_action 09:35:15 INFO - Running main action method: run_tests 09:35:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:35:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:35:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:35:15 INFO - 2016-11-03 09:35:15.437 screenresolution[1917:13208] starting screenresolution argv=screenresolution get 09:35:15 INFO - 2016-11-03 09:35:15.440 screenresolution[1917:13208] Display 0: 1600x1200x32@60 09:35:15 INFO - 2016-11-03 09:35:15.446 screenresolution[1918:13210] starting screenresolution argv=screenresolution list 09:35:15 INFO - Available Modes on Display 0 09:35:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:35:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:35:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:35:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:35:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:35:15 INFO - Intel Iris: 09:35:15 INFO - Chipset Model: Intel Iris 09:35:15 INFO - Type: GPU 09:35:15 INFO - Bus: Built-In 09:35:15 INFO - VRAM (Dynamic, Max): 1536 MB 09:35:15 INFO - Vendor: Intel (0x8086) 09:35:15 INFO - Device ID: 0x0a2e 09:35:15 INFO - Revision ID: 0x0009 09:35:15 INFO - Displays: 09:35:15 INFO - Display: 09:35:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:35:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:35:15 INFO - Display Serial Number: 1600x1200 60 09:35:15 INFO - Main Display: Yes 09:35:15 INFO - Mirror: Off 09:35:15 INFO - Online: Yes 09:35:15 INFO - Rotation: Supported 09:35:15 INFO - Return code: 0 09:35:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:35:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:35:15 INFO - Python 2.7.3 09:35:15 INFO - Return code: 0 09:35:15 INFO - grabbing minidump binary from tooltool 09:35: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')]} 09:35: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 0x100b6ed30>, '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 0x100994db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b66b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:35: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 09:35: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 09:35: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 09:35:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:35:15 INFO - Return code: 0 09:35:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:35:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:35:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:35:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:35:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:35: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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0300', '--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 09:35: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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0300 --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 09:35:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8aavnU58dK/Render', 09:35:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:15 INFO - 'HOME': '/Users/cltbld', 09:35:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:15 INFO - 'LOGNAME': 'cltbld', 09:35:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:35:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:35:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:15 INFO - 'MOZ_NO_REMOTE': '1', 09:35:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:35:15 INFO - 'NO_EM_RESTART': '1', 09:35:15 INFO - 'PAGER': '/bin/cat', 09:35:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:15 INFO - 'PWD': '/builds/slave/test', 09:35:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:35:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:15 INFO - 'SHELL': '/bin/bash', 09:35:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TR786NQ00/Listeners', 09:35:15 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:35:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:15 INFO - 'USER': 'cltbld', 09:35:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:15 INFO - 'XPC_FLAGS': '0x0', 09:35:15 INFO - 'XPC_SERVICE_NAME': '0', 09:35:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35: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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0300', '--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 09:35:16 INFO - mozversion application_buildid: 20161103030205 09:35:16 INFO - mozversion application_changeset: ade8d4a63e57560410de106450f37b50ed71cca5 09:35:16 INFO - mozversion application_display_name: Nightly 09:35:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:35:16 INFO - mozversion application_name: Firefox 09:35:16 INFO - mozversion application_remotingname: firefox 09:35:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:35:16 INFO - mozversion application_vendor: Mozilla 09:35:16 INFO - mozversion application_version: 52.0a1 09:35:16 INFO - mozversion platform_buildid: 20161103030205 09:35:16 INFO - mozversion platform_changeset: ade8d4a63e57560410de106450f37b50ed71cca5 09:35:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:35:16 INFO - mozversion platform_version: 52.0a1 09:35:16 INFO - using testdate: 1478190916 09:35:16 INFO - actual date: 1478190916 09:35:16 INFO - starting webserver on 'localhost:51554' 09:35:16 INFO - SUITE-START | Running 2 tests 09:35:16 INFO - TEST-START | dromaeo_css 09:35:16 INFO - operating with platform_type : mac_ 09:35:16 INFO - Initialising browser for dromaeo_css test... 09:35:16 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpmIaT6c/profile http://localhost:51554/getInfo.html) 09:35:18 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 09:35:18 INFO - PROCESS | 1928 | colorDepth:24 09:35:18 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 09:35:18 INFO - PROCESS | 1928 | __metrics 09:35:18 INFO - PROCESS | 1928 | 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" 09:35:18 INFO - PROCESS | 1928 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:35:19 INFO - TEST-INFO | 1928: exit 0 09:35:19 INFO - Browser initialized. 09:35:19 INFO - Running cycle 1/1 for dromaeo_css test... 09:35:19 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpmIaT6c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:31 INFO - PROCESS | 1931 | RSS: Main: 239874048 09:35:31 INFO - PROCESS | 1931 | 09:36:22 INFO - PROCESS | 1931 | 1478190982272 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] 09:38:36 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51554/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51554/tests/dromaeo/cssquery-ext.html) 09:38:37 INFO - PROCESS | 1931 | RSS: Main: 357662720 09:38:37 INFO - PROCESS | 1931 | 09:41:40 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51554/tests/dromaeo/cssquery-ext.html (next: http://localhost:51554/tests/dromaeo/cssquery-jquery.html) 09:41:40 INFO - PROCESS | 1931 | RSS: Main: 369389568 09:41:40 INFO - PROCESS | 1931 | 09:44:43 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51554/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51554/tests/dromaeo/cssquery-mootools.html) 09:44:43 INFO - PROCESS | 1931 | RSS: Main: 346931200 09:44:43 INFO - PROCESS | 1931 | 09:47:46 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51554/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51554/tests/dromaeo/cssquery-prototype.html) 09:47:46 INFO - PROCESS | 1931 | RSS: Main: 333316096 09:47:46 INFO - PROCESS | 1931 | 09:50:50 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51554/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51554/tests/dromaeo/cssquery-yui.html) 09:50:50 INFO - PROCESS | 1931 | RSS: Main: 330059776 09:50:50 INFO - PROCESS | 1931 | 09:53:54 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51554/tests/dromaeo/cssquery-yui.html (next: http://localhost:51554/tests/dromaeo/cssquery-dojo.html) 09:53:54 INFO - PROCESS | 1931 | RSS: Main: 386052096 09:53:54 INFO - PROCESS | 1931 | 09:53:54 INFO - PROCESS | 1931 | __start_tp_report 09:53:54 INFO - PROCESS | 1931 | _x_x_mozilla_page_load 09:53:54 INFO - PROCESS | 1931 | _x_x_mozilla_page_load_details 09:53:54 INFO - PROCESS | 1931 | |i|pagename|runs| 09:53:54 INFO - PROCESS | 1931 | |0;dojo.html;3319.137024373662;3331.4336562784324;3333.416652085885;3353.0443823510304;3369.8652053917845;5513.7794488220525;5601.383847776747;5607.971960140194;5650.632708873925;5680.8295751127425;5582.7766889324375;5591.8602034949145;5676.574256930737;5706.514946229565;5720.342160651537;5048.848534543969;5082.263071854579;5087.6184286178495;5147;5163.251328557357;4529.909401811962;5234.659747116444;5247.737613119348;5256.737163141846;5306.893862122755;4502.684812063157;5033.496650334974;5191.351081114861;5198;5282.31329927109;5082.644214904958;5135.768890399941;5140.1004824156;5141.434442211354;5141.820036298749;5045.596352291808;5080.669756465818;5099.515546023122;5101.030804147237;5109.105906466353;5563.415841336642;5574.554035677176;5577.135543990647;5598.300212473441;5614.522765564931;5981.342052374235;6049.909251361233;6054.636721796706;6057.636541807462;6067.908981365283;6114.266288045463;6127.250613123855;6162.137300777895;6172.59878107926;6196.907046394308;5704.572157088193;5753.482186603226;5755.740991655385;5758.942410575924;5807.854803629901;6290.056491526216;6298.244210694655;6310.43206111443;6316.715747791361;6357.8728425432155;5923.940760592425;5940.70296485174;5989.8203053908455;6006.159137720722;6099.603525770811;6151.6924153793025;6224.751009959551;6225.128482012522;6233.563650544509;6237.781677641352;5642.576806739429;5747.879163563149;6151.046587778901;6159.538034647419;6166.321704612489;6003.039513677791;6179.783707570214;6183.783567575113;6198.256209254828;6220.315765265817;4827.275908613666;4859.829905953346;4864.854054378375;4897.338859253955;4903.656744027955;4367.781610919441;4390.1878152541885;4394.626456751148;4415.2935530315635;4428.601425871687;5762.769489220468;5764.164196191528;5777.855553611109;5779.653220806765;5781.103928891029;5124.6932032331515;5948.286205655262;5985.102234664835;6168.167297414881;6200.937990620127;5728.541716662657;5743.4256574342235;5793.449622285876;5806.390329015477;5833.82498525045;5375.301210842644;5375.3280839895015;5376.247325374451;5382.3541175059245;5382.892342153214;6191.84520386994;6228.657423841732;6256.436920677161;6266.937330626635;6270.7491700331475;6158.3841615838055;6173.32093469709;6203.875922481435;6220.844478888064;6280.937190628218;5359;6256.124142620128;6272.40412160844;6285.654289013965;6291.937080629318;6115.86811791206;6144.508439324753;6145.262568491809;6170.81487555374;6175.228096487939;6089.48239399656;6093.9085913712215;6139.2325959255095;6142.9078563820685;6146.6004709693725;6027.638341699512;6031.155638210742;6039.335673076046;6044.788432404845;6069.393060693896;5990;6006.069059295816;6016.518678505797;6018.337982821974;6021.458068773832;4668.6498512611015;5791.7683292667825;5796.652200867961;5828.70856457167;5915.319738229987;6008.459238668541;6072.240969878765;6078.6048906820915;6091.42131497507;6120.44915957566;5722;5768.826935191951;5888.4700376966275;5921;5981.700914954322 09:53:54 INFO - PROCESS | 1931 | |1;ext.html;467.9812807487701;496.99751501242486;509.55668568345544;542.8615702995736;552.9438771945585;24082.638760418587;37334.62665373349;38416.42375364365;38607;39662;39266.21467570647;39575.604243957554;39636.80181599088;39653.206935861264;39690.847918572596;9277.304202184829;9463.905360946406;9471.668491602795;9472.573734181944;9478.81042379152;9315.580798864034;9411.905880941205;9412.247020238365;9417.340786144972;9452.243820494345;9369.15677589017;9422.905770942272;9437.95281023594;9438.952805235964;9453.527323633825;9367.765805854873;9410.62357505699;9428.717138485856;9440.811183776355;9447.338686291929;9139.908600913972;9180.357374983687;9205.76985575366;9213.170814626648;9234.1689247968;37914.43128353077;38245.42631860524;38306.23387532234;38344.616553834385;38349.80825095884;3206.9507127661614;3269.29710112327;3310.8841190558455;3512;3745.213505163919;29211.415771684446;29565.40869182626;29697.70302296971;29887.402251955056;30785.076447706604;2811.4517669054544;3133.8903138390046;3154.148379937427;3161.1306890605038;3317.668233176687;477798;483170.58414708055;483421;486539;496220;53829.461705383226;54029.72985135128;54089;54134;54152.45847541553;33036.834815826245;33747.83126084403;53237.467625324025;53294.934101317755;53297.667558311354;14855.851441485447;15303.311350989232;15304.617384565252;15312.540623781304;15324.23378831054;11672.824907626393;11788.823167652492;11789.174757767045;11797.764044719057;11816.704582385337;15924.76112858308;16913.154342283084;17996.460106196835;18040.639187216184;18054.90972545129;7606.23937606246;7653.617319134021;7672.846543069108;7680.615969201629;7741.013378110278;839.3620848155446;906.8809842734481;927.5455027036838;990.2672022703292;1090.6673464593216;23587.528249434916;25470.872645637024;27493.86253068762;28867.625992642974;28867.845286188738;838.1157878438185;1003.6738060130341;1013.7212266626736;1036.6889933020216;1037.2790910317408;14590.708185836223;14790.63023424423;14819.629509262139;15195.468158614396;15335.846641533664;564754;655533;659473;664585;665086;53105.7344713274;53708.46291537113;53711;53814;53845.46154538483;1160.8723040465543;1217.6164508179895;1252.586646406702;1347.2859384526216;1377.1530508737255;22980.655290170995;28045.597720113503;29400.970966016088;29624.703752963058;30082.84958575193;36430.635693642726;44981.10037799291;44983;45015.32477012913;45204.095918082116;483388;487236.56381717866;487482;487997;679428;37239.6276037247;38373.23253534969;38846.223075538896;39014.219715606094;39188.02029949274;43041.569584305005;43434.78282608567;43729.78135109304;43778;44071.77964110287;35902.92184258521;36094.278114438086;36330.4550431749;37122;37426.8128659355;34358.15911295136;35674.643253567134;36840.44739328859;37049.814750926074;38345.80827095846;34234.315313693085;36548.26903461969;36684.81657591695;37153.62846371502;37162.44256336103;31882.36235275328;33221.16947076342;33240.667593324724;33249.50125748164;33452.665473344954;24058.39854003664;24498.265052048468;24723.752762472144;25078.62382064234;25287.87356063208 09:53:54 INFO - PROCESS | 1931 | |2;jquery.html;347.5152162732989;361.4127043554224;368.56325254573335;379.09396542263994;398.45411785853383;14729.852701472995;14996;15777.55447905575;20059.699104513445;20480.28319008835;16873.40943066993;19170.71243931342;19716;20306.796932030677;20609.175632974664;5711.286089238845;5993.61041532299;6006.21919150512;6021.819345419644;6022.337542870281;4627.421572303462;5948.315943666469;6000.75996960121;6006.969965150168;6027.668478233695;5584.6090773645865;5952.880942381172;5965.403459654043;5966.642001479903;5975.790847320344;5796.62321949072;5890.852728681817;5898.675572843492;5900.291964964189;5902.203202567642;4906.31311616374;5046.44489106198;5137.588992880561;5158.200478925773;5158.3552055993005;24742.886570104387;24859.62710559343;24964.875175624005;25164.748352516606;25262.242132736046;2147.194801949269;2408.8072954163626;2443.5235129149823;2671.19864040787;2799.1322689965973;14641.653304474312;15041.247937603077;15096.24518774057;15743.370265189378;15799.894007419485;1733.566608347913;1847.085692582167;1852.2128095559333;1869.8645788830393;1899.838513726335;111004.44497777459;113270.43364783206;113627;115549;116271;10432.426216558162;10466.267361284637;10647.361158330525;10736.677899663022;10835.349879007284;11017.944910275397;12993.285369304778;13000.479980800663;13001.869981300255;13033;8094.51432914027;8173.18268173178;8187.4678145920325;8353.248207661218;8405.285550728135;7210.882313241456;7952.284294413531;8229.753107406668;8253.422260441712;8406.369522285853;6220.34686357935;6341.8097457076365;6377.776777812847;6384.904226436607;6411.5832470889245;1802.8737988340952;1811.8459930905758;1812.9002904840097;1815.7730283714536;1817.1374341300009;865.7272959017889;932.4591736792644;933.6265493802398;976.5263847034183;1021.1473419694638;17738.201780919888;20435.897820511098;21119.366419007456;21778.34664960054;22122.115115395525;862.6463150108425;903.6249956268207;917.3395155488038;945.522511131874;964.5804075227298;10991.780164396667;11339;11570.652880413601;11624.88375116255;11638.767224655629;478529.607351961;495496;523851;528583;529415;45713;72778.63610681913;113256;113815;114896.42551787187;952.2382094324513;984.3306551545019;1007.0995532865301;1008.1128606825946;1025.610268098118;18615.97612131346;18992;19161.041947902828;19200.231990720775;19255.8074419254;49171.508284916694;49985.500144998085;50381;51551;51607.48392516027;69003;70694;71173.64413178108;71221;72333;7608.277213664693;7718.3053525183;7873.724419645285;7906;7989.960050199712;11971.880281197076;12728.6817829555;12838.486460541473;13069;13283.601491955256;10107.3430227035;10227.926067763075;10422.739431514272;10458.477076146315;11333.150013748836;11010.339379637247;11033.393163376091;11080.944595276973;11123.27698699582;11594.014508766511;11565.785592513;11671.824922626338;11723.824142637697;11804.40977951082;11889.048876090064;6566.704498297684;7194.316539928698;7285.817854553678;7360;7720.7683769487;9302.720918372459;9337.626494940127;9923.851142233018;10103.292769506357;10869.347839129678 09:53:54 INFO - PROCESS | 1931 | |3;mootools.html;1339.551250331139;1498.430596373378;1519.6960607878423;1655.8840881138317;1805.241798444653;7479.139898911627;8099.87850182247;8314.29328507076;8400.49597024179;8479.152084791518;8193.631286592117;8202.83594328113;8294.668213271461;8299.917000830004;8312.54281014544;2268.126771193094;2301.8964146613444;2306.2043594959714;2306.411864974429;2307.3424074138875;2261.604219261623;2298.9310320690324;2318.686977258074;2319.8492098013658;2320.4894923116885;2251.4596496840804;2262.321303608919;2283.6574513822975;2292.701948746669;2294.4952110535655;2245.820334373246;2248.3704562722382;2274.601944659686;2277.544491101786;2281.7946384825445;2264.230161745015;2284.0863654538152;2285.8857057147243;2286.062714287151;2304.055337311711;8189;8251.504909705436;8284.958575207085;8292;8316.209960053786;5022.723750193738;5231.633785635007;5237.423883372864;5244;5246.842594722164;3439.4496880499;3546.414841551141;3584.6594573515476;3613.403788374915;3619.4027985382645;1060.973475663107;1062.3466568060664;1068.631322193842;1080.637986274599;1083.6694808083532;8977.640894364284;10608.734781630445;10610.575576976911;10755.462226888625;10769.78460430787;4231.87304380869;4328.632066274402;4382.671299652538;4404.625606823456;4426.247537918497;8403.159684031549;8563.914360856435;8573.957130214309;8577.785555361166;8582.87125693115;4977.452480227172;5081.110805609003;5081.923771143436;5082.669626474268;5084.644074914794;4035.757854528738;4281.400603915455;4415.205263052681;4420.31485119807;4768.356271903318;2993.4911065118545;3284.261041265744;3549.8757543485854;3599.6040435552068;3676.1361080146144;1136.7783282260045;1140.2702270546856;1141.2695874640237;1150.0914277720508;1151.4990978924195;819.8811172379972;856.4475913036057;884.4560595234013;926.1386910173603;926.9907300926905;5028.7737051832755;5566.970110529565;5669.518090962232;5705.4579814917515;5774.88450230993;781.4998401023349;863.3352318714554;875.4178471316503;884.5886662701746;914.5564401265381;3596.478510615946;3625.0756057205244;3701.2597480504005;3742.4947632069866;3783.2055268393947;9721.027897210222;10097.949510252402;10106.039926206999;10143.289969702199;10156.542955567018;10094.596216151418;10095.091441770275;10098.192144628412;10100.595976161017;10122.797544049077;6818.863622727517;7438.7024519019715;7721.65252563636;7903.525788452711;7916.208379162038;5471.316085489314;5553.222548843084;5610.270664813711;5631.155326701028;5655.7172141393585;9114;9252;9342.299327550596;9453.43279403238;9481.810363792823;7785.688572457038;9007.504587247764;9259.953700231456;9575.138237558653;9658.420494770337;7001.649917504206;7214.314640109181;7229.421646268181;7284.125904891447;7290.05229320185;7722.768316950501;7886.921130788619;7953.642086106255;7998.280154786097;8002.639881205476;6048.425399587032;7058.647067646494;7123.038389817309;7124.323189297009;7160.928390716234;6976.093107895941;6993.335633114846;7001.054857594159;7045.154581450259;7055.1886533049355;5795.797147099831;6595.208574971034;6667.266600674019;6749.257581666111;6761.019652150509;4658.743769092597;4730.526947305242;4737.597304229179;4785.138675038526;4902.411710594752;4621.838235661736;4770.689905156154;4807.663463557517;4824.758762061953;4899.3385892904 09:53:54 INFO - PROCESS | 1931 | |4;prototype.html;252.4937500311875;277.3371641776154;294.0399595321275;304.5401443819831;307.64159753886725;4000.7999600019994;5315.734213289339;5346.679199248043;5348.977827527444;5396.43337449568;4525.932111018329;4638.840289927518;4937.111319962405;5102.285680004802;5220.564344805181;3280.343931213755;3460.1349662584353;3710;3972.7553932028454;3979.283728928799;3985.7409268397605;4004.097029653507;4014.1570270243283;4019.5980401959714;4023.577524359944;3472.9652703472893;3846;3969.8213580388806;3971.4837071180705;3993.361062230044;3353.541209573831;3487.738419618539;3856.6914646828186;3985.784335777587;4009.97995010026;3478.617352091268;3491.9650803491895;3495.1611613212644;3497.7264777894316;3513.7013353864954;3754.699624030071;4299.785010749481;4791.976040119812;5055.292259083732;5136.666116702402;4048.0247018441423;4537.3647689323525;4562.703424277444;4597.95402045982;4625.213713668684;5534.225208470817;5538.723063846832;5563.860903477405;5564.888702225933;5565.638233514808;3242.8702851886133;3828.4686125549724;3848.517706079563;3856.6914646828186;3857.4406711027023;5629.774809007635;5725.5133313667975;5771.336296325934;5794.725160464691;5813.040848260074;3895.2988462077096;3902.843886244518;3911.3741801311653;3919.2553414851086;3926.430667553189;3941.4481972523868;3952.5256969163884;3957.4261732049267;3958.9208215835524;3968;3834.7123965702685;4315.4605674290715;4543.659225558096;4563.315502674625;4620.44554653437;4419.806652203887;4644.535546445329;5379.870227252242;5554.917021053273;5565;1209.9455524501418;1286.7619490394306;1303.328785675378;1323.2060763541797;1355.6678613739698;857.6097875466647;867.5185272174004;877.1316396767155;902.3322741171486;921.7188757428914;4017.819198136091;4576.039031803292;5034.697918124924;5151.381834179922;5224.779468725852;5633;5706.914396284059;5724.4561766632105;5728.570357223225;5731.942680573141;4417.514073451917;4988.825391111366;5058.418281897629;5075.2387141929;5191.923230707672;2564.6665933428535;2639.4457163995394;2745.805574575066;2941.9703103913457;2954.7340739333563;5094.77073531692;5740.454656807597;5910.586258961917;5958.374370691102;5985.850353741192;4888.560029597354;5208.9739551302755;5530.949119667251;5804.169248296104;5815.214945982323;5180.792768289301;5188.028549151299;5871.941280587225;5878.617889837233;5906.704664766745;4522.8643140705835;4614.861554153381;5300.946990530046;5572.972135139461;5664.716764161858;4507.782898617421;4730.6215502759005;5568.526675232489;5603.627111357743;5613.298337707786;5063.518965698253;5890.882182356415;5904.261967254093;5921.585489015728;5972.20833749882;3056.373443444133;3105.813651180846;3775;3788.726763941723;3801.6198380161763;5284.837335786121;5286.603504737083;5738.799142030009;5740.412242348532;5748.511376532874;5159.922601160911;5579.9163012555655;5592.972035139798;5602.5517958562405;5649.322081350264;4715.585324402597;5277.677821758414;5498;5560.304961879765;5615.578831587565;4545.545445455428;4875.878992638213;4973.328600639012;5439.755211015435;5497.151993604413;5157.1208821943765;5437.981920034419;5507.430382341014;5568.359638641608;5583.771570254537;4528.592426681483;5458.344998600193;5460.34475862899;5492.972535137299;5495.313085864267 09:53:54 INFO - PROCESS | 1931 | |5;yui.html;679.9116114905063;692.518699503845;750.5459197185703;786.3551887452288;808.6644042722271;14250.2162381069;14799.778003329946;14925.477608283712;15203.391864325442;15213.467528636485;13443.596692099252;13621.795673064888;13635.386407611657;13679.589612311622;14166.362513686883;817.4890693316677;832.1512057701141;832.6586099699125;834.8121672623641;835.1356346181703;665.2715276771927;687.1033301541486;832.5961908474385;835.4945258118842;835.9205875441854;718.0629278791201;871.8910136232971;888.7422647432269;900.2662829793697;901.8737376767258;888.5601627194548;890.6170346750895;890.8084761776249;891.0643823984855;893.115815342806;656.655255990604;657.3951964192906;657.4149007383432;658.1378394303478;659.4328877165634;11875.52497900083;12394.132410731252;14081.014328996975;14673.559793206221;14694.4856930008;10695.251332406735;13644.317784110854;13854.792178117335;13937.372818223204;14004.789928151085;11125.777484450266;11190;11285.887141128565;11525.596604118815;11635.592754253561;3713;3717.8512859485586;3740.401535754266;3773.660370566662;3870.5161854768153;84255.99999999939;84503.7324440134;84665.19006644716;84971;85061.57469212615;9825.410475371358;10686.89313106859;10759.731006724738;10886.782264354668;11289.71775705614;19052.61894762097;19907.303244386378;21245.46886327823;21258;21265;7671.079470463468;7800.297973182441;7898.644560994769;7950.840983180419;7991.560464174526;4264.232438161161;4274.273373526508;4401.889952751207;4423.203823311835;4423.447069116361;3405.6764607362757;3500.7024402925526;3509.701675357572;3515.4199557072798;3535.4696795480368;656.2420404432887;663.8274048747361;670.4435318685479;670.6378555579932;675.6993138053617;500.5420040662826;521.1453216724575;539.7840863654569;549.3572520151432;583.716594157862;7239.529430586995;8253.79365515867;8762.780930476789;9040;9096.681616143404;584.3163498706524;615.8398816307792;629.6808186848544;640.276487569044;658.4666420199653;3502.404591219499;4339.479262488521;4345.565443455629;4397.714148580332;4457.353683715908;97297.05405891842;104593.90812183866;109672.4516377413;113232.43383783028;113638;57576.8484630305;75487.62256188794;76341.61829190819;77577.63851189513;87426.1257387418;73480;73648;74258.6287068572;77330;78189.60905195438;7928.909101789213;8975.28197744192;9147.634094636407;9358.108613951883;9647.421154730739;49225;50193.74903125461;52130.478695212565;52359.95280094301;53162;35722.464163038094;40350.1929961405;51932.74033629808;66592.66703666645;69766;10291.691249262534;12152.270863748203;12323.383830808603;12348.444320005441;12380.62858114258;13961.092528985584;14072.78890816659;14199.29003549839;14336.56990290293;14428.278586070865;12334.81497777516;12379.566715164927;12918.547850825176;12918.677033073873;13278.80081798793;11899.76200476003;12626.368681565702;12836.871631283568;13018.609441716764;13442.059055865995;9934.503274836374;12107.213031152947;12372.62882113538;12401.62795116148;12932.741345172855;9822.65620703272;10076.790785105833;10084.747881303027;10612.628558000433;11560.768784624091;10435.791284174426;10947.233693641369;11134.554617815196;11259.887401125883;11317.773644527228 09:53:54 INFO - PROCESS | 1931 | __end_tp_report 09:53:54 INFO - PROCESS | 1931 | __start_cc_report 09:53:54 INFO - PROCESS | 1931 | _x_x_mozilla_cycle_collect,1293 09:53:54 INFO - PROCESS | 1931 | __end_cc_report 09:53:54 INFO - PROCESS | 1931 | __startTimestamp1478192034455__endTimestamp 09:53:54 INFO - PROCESS | 1931 | 09:53:54 INFO - PROCESS | 1931 | ------- Summary: start ------- 09:53:54 INFO - PROCESS | 1931 | Number of tests: 6 09:53:54 INFO - PROCESS | 1931 | 09:53:54 INFO - PROCESS | 1931 | [#0] dojo.html Cycles:165 Average:5659.53 Median:5807.85 stddev:640.97 (11.0%) stddev-sans-first:616.08 09:53:54 INFO - PROCESS | 1931 | Values: 3319.1 3331.4 3333.4 3353.0 3369.9 5513.8 5601.4 5608.0 5650.6 5680.8 5582.8 5591.9 5676.6 5706.5 5720.3 5048.8 5082.3 5087.6 5147.0 5163.3 4529.9 5234.7 5247.7 5256.7 5306.9 4502.7 5033.5 5191.4 5198.0 5282.3 5082.6 5135.8 5140.1 5141.4 5141.8 5045.6 5080.7 5099.5 5101.0 5109.1 5563.4 5574.6 5577.1 5598.3 5614.5 5981.3 6049.9 6054.6 6057.6 6067.9 6114.3 6127.3 6162.1 6172.6 6196.9 5704.6 5753.5 5755.7 5758.9 5807.9 6290.1 6298.2 6310.4 6316.7 6357.9 5923.9 5940.7 5989.8 6006.2 6099.6 6151.7 6224.8 6225.1 6233.6 6237.8 5642.6 5747.9 6151.0 6159.5 6166.3 6003.0 6179.8 6183.8 6198.3 6220.3 4827.3 4859.8 4864.9 4897.3 4903.7 4367.8 4390.2 4394.6 4415.3 4428.6 5762.8 5764.2 5777.9 5779.7 5781.1 5124.7 5948.3 5985.1 6168.2 6200.9 5728.5 5743.4 5793.4 5806.4 5833.8 5375.3 5375.3 5376.2 5382.4 5382.9 6191.8 6228.7 6256.4 6266.9 6270.7 6158.4 6173.3 6203.9 6220.8 6280.9 5359.0 6256.1 6272.4 6285.7 6291.9 6115.9 6144.5 6145.3 6170.8 6175.2 6089.5 6093.9 6139.2 6142.9 6146.6 6027.6 6031.2 6039.3 6044.8 6069.4 5990.0 6006.1 6016.5 6018.3 6021.5 4668.6 5791.8 5796.7 5828.7 5915.3 6008.5 6072.2 6078.6 6091.4 6120.4 5722.0 5768.8 5888.5 5921.0 5981.7 09:53:54 INFO - PROCESS | 1931 | 09:53:54 INFO - PROCESS | 1931 | [#1] ext.html Cycles:180 Average:67229.63 Median:38361.52 stddev:149042.63 (388.5%) stddev-sans-first:149376.45 09:53:54 INFO - PROCESS | 1931 | Values: 468.0 497.0 509.6 542.9 552.9 24082.6 37334.6 38416.4 38607.0 39662.0 39266.2 39575.6 39636.8 39653.2 39690.8 9277.3 9463.9 9471.7 9472.6 9478.8 9315.6 9411.9 9412.2 9417.3 9452.2 9369.2 9422.9 9438.0 9439.0 9453.5 9367.8 9410.6 9428.7 9440.8 9447.3 9139.9 9180.4 9205.8 9213.2 9234.2 37914.4 38245.4 38306.2 38344.6 38349.8 3207.0 3269.3 3310.9 3512.0 3745.2 29211.4 29565.4 29697.7 29887.4 30785.1 2811.5 3133.9 3154.1 3161.1 3317.7 477798.0 483170.6 483421.0 486539.0 496220.0 53829.5 54029.7 54089.0 54134.0 54152.5 33036.8 33747.8 53237.5 53294.9 53297.7 14855.9 15303.3 15304.6 15312.5 15324.2 11672.8 11788.8 11789.2 11797.8 11816.7 15924.8 16913.2 17996.5 18040.6 18054.9 7606.2 7653.6 7672.8 7680.6 7741.0 839.4 906.9 927.5 990.3 1090.7 23587.5 25470.9 27493.9 28867.6 28867.8 838.1 1003.7 1013.7 1036.7 1037.3 14590.7 14790.6 14819.6 15195.5 15335.8 564754.0 655533.0 659473.0 664585.0 665086.0 53105.7 53708.5 53711.0 53814.0 53845.5 1160.9 1217.6 1252.6 1347.3 1377.2 22980.7 28045.6 29401.0 29624.7 30082.8 36430.6 44981.1 44983.0 45015.3 45204.1 483388.0 487236.6 487482.0 487997.0 679428.0 37239.6 38373.2 38846.2 39014.2 39188.0 43041.6 43434.8 43729.8 43778.0 44071.8 35902.9 36094.3 36330.5 37122.0 37426.8 34358.2 35674.6 36840.4 37049.8 38345.8 34234.3 36548.3 36684.8 37153.6 37162.4 31882.4 33221.2 33240.7 33249.5 33452.7 24058.4 24498.3 24723.8 25078.6 25287.9 09:53:54 INFO - PROCESS | 1931 | 09:53:54 INFO - PROCESS | 1931 | [#2] jquery.html Cycles:180 Average:31150.88 Median:25213.50 stddev:85296.14 (338.3%) stddev-sans-first:85504.06 09:53:54 INFO - PROCESS | 1931 | Values: 347.5 361.4 368.6 379.1 398.5 14729.9 14996.0 15777.6 20059.7 20480.3 16873.4 19170.7 19716.0 20306.8 20609.2 5711.3 5993.6 6006.2 6021.8 6022.3 4627.4 5948.3 6000.8 6007.0 6027.7 5584.6 5952.9 5965.4 5966.6 5975.8 5796.6 5890.9 5898.7 5900.3 5902.2 4906.3 5046.4 5137.6 5158.2 5158.4 24742.9 24859.6 24964.9 25164.7 25262.2 2147.2 2408.8 2443.5 2671.2 2799.1 14641.7 15041.2 15096.2 15743.4 15799.9 1733.6 1847.1 1852.2 1869.9 1899.8 111004.4 113270.4 113627.0 115549.0 116271.0 10432.4 10466.3 10647.4 10736.7 10835.3 11017.9 12993.3 13000.5 13001.9 13033.0 8094.5 8173.2 8187.5 8353.2 8405.3 7210.9 7952.3 8229.8 8253.4 8406.4 6220.3 6341.8 6377.8 6384.9 6411.6 1802.9 1811.8 1812.9 1815.8 1817.1 865.7 932.5 933.6 976.5 1021.1 17738.2 20435.9 21119.4 21778.3 22122.1 862.6 903.6 917.3 945.5 964.6 10991.8 11339.0 11570.7 11624.9 11638.8 478529.6 495496.0 523851.0 528583.0 529415.0 45713.0 72778.6 113256.0 113815.0 114896.4 952.2 984.3 1007.1 1008.1 1025.6 18616.0 18992.0 19161.0 19200.2 19255.8 49171.5 49985.5 50381.0 51551.0 51607.5 69003.0 70694.0 71173.6 71221.0 72333.0 7608.3 7718.3 7873.7 7906.0 7990.0 11971.9 12728.7 12838.5 13069.0 13283.6 10107.3 10227.9 10422.7 10458.5 11333.2 11010.3 11033.4 11080.9 11123.3 11594.0 11565.8 11671.8 11723.8 11804.4 11889.0 6566.7 7194.3 7285.8 7360.0 7720.8 9302.7 9337.6 9923.9 10103.3 10869.3 09:53:54 INFO - PROCESS | 1931 | 09:53:54 INFO - PROCESS | 1931 | [#3] mootools.html Cycles:180 Average:5347.55 Median:5054.94 stddev:2929.85 (58.0%) stddev-sans-first:2922.58 09:53:54 INFO - PROCESS | 1931 | Values: 1339.6 1498.4 1519.7 1655.9 1805.2 7479.1 8099.9 8314.3 8400.5 8479.2 8193.6 8202.8 8294.7 8299.9 8312.5 2268.1 2301.9 2306.2 2306.4 2307.3 2261.6 2298.9 2318.7 2319.8 2320.5 2251.5 2262.3 2283.7 2292.7 2294.5 2245.8 2248.4 2274.6 2277.5 2281.8 2264.2 2284.1 2285.9 2286.1 2304.1 8189.0 8251.5 8285.0 8292.0 8316.2 5022.7 5231.6 5237.4 5244.0 5246.8 3439.4 3546.4 3584.7 3613.4 3619.4 1061.0 1062.3 1068.6 1080.6 1083.7 8977.6 10608.7 10610.6 10755.5 10769.8 4231.9 4328.6 4382.7 4404.6 4426.2 8403.2 8563.9 8574.0 8577.8 8582.9 4977.5 5081.1 5081.9 5082.7 5084.6 4035.8 4281.4 4415.2 4420.3 4768.4 2993.5 3284.3 3549.9 3599.6 3676.1 1136.8 1140.3 1141.3 1150.1 1151.5 819.9 856.4 884.5 926.1 927.0 5028.8 5567.0 5669.5 5705.5 5774.9 781.5 863.3 875.4 884.6 914.6 3596.5 3625.1 3701.3 3742.5 3783.2 9721.0 10097.9 10106.0 10143.3 10156.5 10094.6 10095.1 10098.2 10100.6 10122.8 6818.9 7438.7 7721.7 7903.5 7916.2 5471.3 5553.2 5610.3 5631.2 5655.7 9114.0 9252.0 9342.3 9453.4 9481.8 7785.7 9007.5 9260.0 9575.1 9658.4 7001.6 7214.3 7229.4 7284.1 7290.1 7722.8 7886.9 7953.6 7998.3 8002.6 6048.4 7058.6 7123.0 7124.3 7160.9 6976.1 6993.3 7001.1 7045.2 7055.2 5795.8 6595.2 6667.3 6749.3 6761.0 4658.7 4730.5 4737.6 4785.1 4902.4 4621.8 4770.7 4807.7 4824.8 4899.3 09:53:54 INFO - PROCESS | 1931 | 09:53:54 INFO - PROCESS | 1931 | [#4] prototype.html Cycles:180 Average:4430.53 Median:4955.22 stddev:1380.91 (27.9%) stddev-sans-first:1348.70 09:53:54 INFO - PROCESS | 1931 | Values: 252.5 277.3 294.0 304.5 307.6 4000.8 5315.7 5346.7 5349.0 5396.4 4525.9 4638.8 4937.1 5102.3 5220.6 3280.3 3460.1 3710.0 3972.8 3979.3 3985.7 4004.1 4014.2 4019.6 4023.6 3473.0 3846.0 3969.8 3971.5 3993.4 3353.5 3487.7 3856.7 3985.8 4010.0 3478.6 3492.0 3495.2 3497.7 3513.7 3754.7 4299.8 4792.0 5055.3 5136.7 4048.0 4537.4 4562.7 4598.0 4625.2 5534.2 5538.7 5563.9 5564.9 5565.6 3242.9 3828.5 3848.5 3856.7 3857.4 5629.8 5725.5 5771.3 5794.7 5813.0 3895.3 3902.8 3911.4 3919.3 3926.4 3941.4 3952.5 3957.4 3958.9 3968.0 3834.7 4315.5 4543.7 4563.3 4620.4 4419.8 4644.5 5379.9 5554.9 5565.0 1209.9 1286.8 1303.3 1323.2 1355.7 857.6 867.5 877.1 902.3 921.7 4017.8 4576.0 5034.7 5151.4 5224.8 5633.0 5706.9 5724.5 5728.6 5731.9 4417.5 4988.8 5058.4 5075.2 5191.9 2564.7 2639.4 2745.8 2942.0 2954.7 5094.8 5740.5 5910.6 5958.4 5985.9 4888.6 5209.0 5530.9 5804.2 5815.2 5180.8 5188.0 5871.9 5878.6 5906.7 4522.9 4614.9 5300.9 5573.0 5664.7 4507.8 4730.6 5568.5 5603.6 5613.3 5063.5 5890.9 5904.3 5921.6 5972.2 3056.4 3105.8 3775.0 3788.7 3801.6 5284.8 5286.6 5738.8 5740.4 5748.5 5159.9 5579.9 5593.0 5602.6 5649.3 4715.6 5277.7 5498.0 5560.3 5615.6 4545.5 4875.9 4973.3 5439.8 5497.2 5157.1 5438.0 5507.4 5568.4 5583.8 4528.6 5458.3 5460.3 5493.0 5495.3 09:53:54 INFO - PROCESS | 1931 | 09:53:54 INFO - PROCESS | 1931 | [#5] yui.html Cycles:180 Average:18823.96 Median:4423.33 stddev:26860.63 (607.2%) stddev-sans-first:26901.43 09:53:54 INFO - PROCESS | 1931 | Values: 679.9 692.5 750.5 786.4 808.7 14250.2 14799.8 14925.5 15203.4 15213.5 13443.6 13621.8 13635.4 13679.6 14166.4 817.5 832.2 832.7 834.8 835.1 665.3 687.1 832.6 835.5 835.9 718.1 871.9 888.7 900.3 901.9 888.6 890.6 890.8 891.1 893.1 656.7 657.4 657.4 658.1 659.4 11875.5 12394.1 14081.0 14673.6 14694.5 10695.3 13644.3 13854.8 13937.4 14004.8 11125.8 11190.0 11285.9 11525.6 11635.6 3713.0 3717.9 3740.4 3773.7 3870.5 84256.0 84503.7 84665.2 84971.0 85061.6 9825.4 10686.9 10759.7 10886.8 11289.7 19052.6 19907.3 21245.5 21258.0 21265.0 7671.1 7800.3 7898.6 7950.8 7991.6 4264.2 4274.3 4401.9 4423.2 4423.4 3405.7 3500.7 3509.7 3515.4 3535.5 656.2 663.8 670.4 670.6 675.7 500.5 521.1 539.8 549.4 583.7 7239.5 8253.8 8762.8 9040.0 9096.7 584.3 615.8 629.7 640.3 658.5 3502.4 4339.5 4345.6 4397.7 4457.4 97297.1 104593.9 109672.5 113232.4 113638.0 57576.8 75487.6 76341.6 77577.6 87426.1 73480.0 73648.0 74258.6 77330.0 78189.6 7928.9 8975.3 9147.6 9358.1 9647.4 49225.0 50193.7 52130.5 52360.0 53162.0 35722.5 40350.2 51932.7 66592.7 69766.0 10291.7 12152.3 12323.4 12348.4 12380.6 13961.1 14072.8 14199.3 14336.6 14428.3 12334.8 12379.6 12918.5 12918.7 13278.8 11899.8 12626.4 12836.9 13018.6 13442.1 9934.5 12107.2 12372.6 12401.6 12932.7 9822.7 10076.8 10084.7 10612.6 11560.8 10435.8 10947.2 11134.6 11259.9 11317.8 09:53:54 INFO - PROCESS | 1931 | -------- Summary: end -------- 09:53:54 INFO - PROCESS | 1931 | 09:53:55 INFO - TEST-INFO | 1931: exit 0 09:53:55 INFO - TEST-OK | dromaeo_css | took 1119051ms 09:53:55 INFO - TEST-START | kraken 09:53:55 INFO - operating with platform_type : mac_ 09:53:55 INFO - Initialising browser for kraken test... 09:53:55 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmp4ZOgOQ/profile http://localhost:51554/getInfo.html) 09:53:57 INFO - PROCESS | 1935 | __metrics Screen width/height:1600/1200 09:53:57 INFO - PROCESS | 1935 | colorDepth:24 09:53:57 INFO - PROCESS | 1935 | Browser inner width/height: 1024/689 09:53:57 INFO - PROCESS | 1935 | __metrics 09:53:57 INFO - PROCESS | 1935 | 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" 09:53:57 INFO - PROCESS | 1935 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:53:58 INFO - TEST-INFO | 1935: exit 0 09:53:58 INFO - Browser initialized. 09:53:58 INFO - Running cycle 1/1 for kraken test... 09:53:58 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmp4ZOgOQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:54:10 INFO - PROCESS | 1937 | RSS: Main: 240168960 09:54:10 INFO - PROCESS | 1937 | 09:54:12 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:51554/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51554/tests/kraken/driver.html?testName=audio-beat-detection) 09:54:13 INFO - PROCESS | 1937 | RSS: Main: 290320384 09:54:13 INFO - PROCESS | 1937 | 09:54:16 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:51554/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51554/tests/kraken/driver.html?testName=audio-dft) 09:54:17 INFO - PROCESS | 1937 | RSS: Main: 289132544 09:54:17 INFO - PROCESS | 1937 | 09:54:20 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:51554/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51554/tests/kraken/driver.html?testName=audio-fft) 09:54:21 INFO - PROCESS | 1937 | RSS: Main: 381939712 09:54:21 INFO - PROCESS | 1937 | 09:54:23 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:51554/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51554/tests/kraken/driver.html?testName=audio-oscillator) 09:54:23 INFO - PROCESS | 1937 | RSS: Main: 283111424 09:54:23 INFO - PROCESS | 1937 | 09:54:25 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:51554/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51554/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:54:26 INFO - PROCESS | 1937 | RSS: Main: 288133120 09:54:26 INFO - PROCESS | 1937 | 09:54:29 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:51554/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51554/tests/kraken/driver.html?testName=imaging-darkroom) 09:54:29 INFO - PROCESS | 1937 | RSS: Main: 326209536 09:54:29 INFO - PROCESS | 1937 | 09:54:32 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:51554/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51554/tests/kraken/driver.html?testName=imaging-desaturate) 09:54:33 INFO - PROCESS | 1937 | RSS: Main: 328433664 09:54:33 INFO - PROCESS | 1937 | 09:54:35 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:51554/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51554/tests/kraken/driver.html?testName=json-parse-financial) 09:54:36 INFO - PROCESS | 1937 | RSS: Main: 327360512 09:54:36 INFO - PROCESS | 1937 | 09:54:38 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:51554/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51554/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:54:38 INFO - PROCESS | 1937 | RSS: Main: 301973504 09:54:38 INFO - PROCESS | 1937 | 09:54:40 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:51554/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51554/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:54:41 INFO - PROCESS | 1937 | RSS: Main: 303362048 09:54:41 INFO - PROCESS | 1937 | 09:54:43 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:51554/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51554/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:54:44 INFO - PROCESS | 1937 | RSS: Main: 311734272 09:54:44 INFO - PROCESS | 1937 | 09:54:46 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:51554/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51554/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:54:47 INFO - PROCESS | 1937 | RSS: Main: 322560000 09:54:47 INFO - PROCESS | 1937 | 09:54:50 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:51554/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51554/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:54:51 INFO - PROCESS | 1937 | RSS: Main: 316284928 09:54:51 INFO - PROCESS | 1937 | 09:54:52 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:51554/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51554/tests/kraken/driver.html?testName=ai-astar) 09:54:53 INFO - PROCESS | 1937 | RSS: Main: 360464384 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | __start_tp_report 09:54:53 INFO - PROCESS | 1937 | _x_x_mozilla_page_load 09:54:53 INFO - PROCESS | 1937 | _x_x_mozilla_page_load_details 09:54:53 INFO - PROCESS | 1937 | |i|pagename|runs| 09:54:53 INFO - PROCESS | 1937 | |0;ai-astar;113;113;114;113;113;114;113;114;114;114 09:54:53 INFO - PROCESS | 1937 | |1;audio-beat-detection;149;117;113;167;140;139;124;114;136;112 09:54:53 INFO - PROCESS | 1937 | |2;audio-dft;182;182;187;184;186;184;181;186;183;186 09:54:53 INFO - PROCESS | 1937 | |3;audio-fft;81;60;59;89;60;60;62;61;84;57 09:54:53 INFO - PROCESS | 1937 | |4;audio-oscillator;89;70;91;71;68;70;89;68;70;92 09:54:53 INFO - PROCESS | 1937 | |5;imaging-gaussian-blur;87;86;88;85;86;85;89;86;88;85 09:54:53 INFO - PROCESS | 1937 | |6;imaging-darkroom;137;133;94;95;94;93;93;95;94;93 09:54:53 INFO - PROCESS | 1937 | |7;imaging-desaturate;81;84;83;80;85;81;85;84;81;84 09:54:53 INFO - PROCESS | 1937 | |8;json-parse-financial;56;54;54;54;54;56;56;55;55;56 09:54:53 INFO - PROCESS | 1937 | |9;json-stringify-tinderbox;66;72;77;76;73;70;71;69;70;72 09:54:53 INFO - PROCESS | 1937 | |10;stanford-crypto-aes;88;91;89;86;86;89;87;84;86;93 09:54:53 INFO - PROCESS | 1937 | |11;stanford-crypto-ccm;155;160;147;147;146;144;146;146;192;145 09:54:53 INFO - PROCESS | 1937 | |12;stanford-crypto-pbkdf2;172;170;172;174;172;174;172;171;173;173 09:54:53 INFO - PROCESS | 1937 | |13;stanford-crypto-sha256-iterative;64;65;63;66;66;67;67;68;66;67 09:54:53 INFO - PROCESS | 1937 | __end_tp_report 09:54:53 INFO - PROCESS | 1937 | __start_cc_report 09:54:53 INFO - PROCESS | 1937 | _x_x_mozilla_cycle_collect,1976 09:54:53 INFO - PROCESS | 1937 | __end_cc_report 09:54:53 INFO - PROCESS | 1937 | __startTimestamp1478192093229__endTimestamp 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | ------- Summary: start ------- 09:54:53 INFO - PROCESS | 1937 | Number of tests: 14 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | [#0] ai-astar Cycles:10 Average:113.50 Median:114.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:54:53 INFO - PROCESS | 1937 | Values: 113.0 113.0 114.0 113.0 113.0 114.0 113.0 114.0 114.0 114.0 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:137.50 stddev:18.28 (13.3%) stddev-sans-first:18.21 09:54:53 INFO - PROCESS | 1937 | Values: 149.0 117.0 113.0 167.0 140.0 139.0 124.0 114.0 136.0 112.0 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | [#2] audio-dft Cycles:10 Average:184.10 Median:185.00 stddev:2.08 (1.1%) stddev-sans-first:2.06 09:54:53 INFO - PROCESS | 1937 | Values: 182.0 182.0 187.0 184.0 186.0 184.0 181.0 186.0 183.0 186.0 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | [#3] audio-fft Cycles:10 Average:67.30 Median:61.50 stddev:12.20 (19.8%) stddev-sans-first:11.89 09:54:53 INFO - PROCESS | 1937 | Values: 81.0 60.0 59.0 89.0 60.0 60.0 62.0 61.0 84.0 57.0 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:80.00 stddev:10.79 (13.5%) stddev-sans-first:10.65 09:54:53 INFO - PROCESS | 1937 | Values: 89.0 70.0 91.0 71.0 68.0 70.0 89.0 68.0 70.0 92.0 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:86.50 stddev:1.43 (1.7%) stddev-sans-first:1.51 09:54:53 INFO - PROCESS | 1937 | Values: 87.0 86.0 88.0 85.0 86.0 85.0 89.0 86.0 88.0 85.0 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | [#6] imaging-darkroom Cycles:10 Average:102.10 Median:94.00 stddev:17.38 (18.5%) stddev-sans-first:13.07 09:54:53 INFO - PROCESS | 1937 | Values: 137.0 133.0 94.0 95.0 94.0 93.0 93.0 95.0 94.0 93.0 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | [#7] imaging-desaturate Cycles:10 Average:82.80 Median:84.00 stddev:1.87 (2.2%) stddev-sans-first:1.87 09:54:53 INFO - PROCESS | 1937 | Values: 81.0 84.0 83.0 80.0 85.0 81.0 85.0 84.0 81.0 84.0 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.50 stddev:0.94 (1.7%) stddev-sans-first:0.93 09:54:53 INFO - PROCESS | 1937 | Values: 56.0 54.0 54.0 54.0 54.0 56.0 56.0 55.0 55.0 56.0 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:72.00 stddev:3.24 (4.5%) stddev-sans-first:2.73 09:54:53 INFO - PROCESS | 1937 | Values: 66.0 72.0 77.0 76.0 73.0 70.0 71.0 69.0 70.0 72.0 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:88.50 stddev:2.69 (3.0%) stddev-sans-first:2.85 09:54:53 INFO - PROCESS | 1937 | Values: 88.0 91.0 89.0 86.0 86.0 89.0 87.0 84.0 86.0 93.0 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | [#11] stanford-crypto-ccm Cycles:10 Average:152.80 Median:147.00 stddev:14.67 (10.0%) stddev-sans-first:15.54 09:54:53 INFO - PROCESS | 1937 | Values: 155.0 160.0 147.0 147.0 146.0 144.0 146.0 146.0 192.0 145.0 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:172.50 stddev:1.25 (0.7%) stddev-sans-first:1.32 09:54:53 INFO - PROCESS | 1937 | Values: 172.0 170.0 172.0 174.0 172.0 174.0 172.0 171.0 173.0 173.0 09:54:53 INFO - PROCESS | 1937 | 09:54:53 INFO - PROCESS | 1937 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.50 stddev:1.52 (2.3%) stddev-sans-first:1.45 09:54:53 INFO - PROCESS | 1937 | Values: 64.0 65.0 63.0 66.0 66.0 67.0 67.0 68.0 66.0 67.0 09:54:53 INFO - PROCESS | 1937 | -------- Summary: end -------- 09:54:53 INFO - PROCESS | 1937 | 09:54:54 INFO - TEST-INFO | 1937: exit 0 09:54:54 INFO - TEST-OK | kraken | took 59146ms 09:54:54 INFO - SUITE-END | took 1178s 09:54:54 INFO - Completed test suite (00:19:38) 09:54:54 INFO - javascript benchmark 09:54:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3319.137024373662, 3331.4336562784324, 3333.416652085885, 3353.0443823510304, 3369.8652053917845, 5513.7794488220525, 5601.383847776747, 5607.971960140194, 5650.632708873925, 5680.8295751127425, 5582.7766889324375, 5591.8602034949145, 5676.574256930737, 5706.514946229565, 5720.342160651537, 5048.848534543969, 5082.263071854579, 5087.6184286178495, 5147.0, 5163.251328557357, 4529.909401811962, 5234.659747116444, 5247.737613119348, 5256.737163141846, 5306.893862122755, 4502.684812063157, 5033.496650334974, 5191.351081114861, 5198.0, 5282.31329927109, 5082.644214904958, 5135.768890399941, 5140.1004824156, 5141.434442211354, 5141.820036298749, 5045.596352291808, 5080.669756465818, 5099.515546023122, 5101.030804147237, 5109.105906466353, 5563.415841336642, 5574.554035677176, 5577.135543990647, 5598.300212473441, 5614.522765564931, 5981.342052374235, 6049.909251361233, 6054.636721796706, 6057.636541807462, 6067.908981365283, 6114.266288045463, 6127.250613123855, 6162.137300777895, 6172.59878107926, 6196.907046394308, 5704.572157088193, 5753.482186603226, 5755.740991655385, 5758.942410575924, 5807.854803629901, 6290.056491526216, 6298.244210694655, 6310.43206111443, 6316.715747791361, 6357.8728425432155, 5923.940760592425, 5940.70296485174, 5989.8203053908455, 6006.159137720722, 6099.603525770811, 6151.6924153793025, 6224.751009959551, 6225.128482012522, 6233.563650544509, 6237.781677641352, 5642.576806739429, 5747.879163563149, 6151.046587778901, 6159.538034647419, 6166.321704612489, 6003.039513677791, 6179.783707570214, 6183.783567575113, 6198.256209254828, 6220.315765265817, 4827.275908613666, 4859.829905953346, 4864.854054378375, 4897.338859253955, 4903.656744027955, 4367.781610919441, 4390.1878152541885, 4394.626456751148, 4415.2935530315635, 4428.601425871687, 5762.769489220468, 5764.164196191528, 5777.855553611109, 5779.653220806765, 5781.103928891029, 5124.6932032331515, 5948.286205655262, 5985.102234664835, 6168.167297414881, 6200.937990620127, 5728.541716662657, 5743.4256574342235, 5793.449622285876, 5806.390329015477, 5833.82498525045, 5375.301210842644, 5375.3280839895015, 5376.247325374451, 5382.3541175059245, 5382.892342153214, 6191.84520386994, 6228.657423841732, 6256.436920677161, 6266.937330626635, 6270.7491700331475, 6158.3841615838055, 6173.32093469709, 6203.875922481435, 6220.844478888064, 6280.937190628218, 5359.0, 6256.124142620128, 6272.40412160844, 6285.654289013965, 6291.937080629318, 6115.86811791206, 6144.508439324753, 6145.262568491809, 6170.81487555374, 6175.228096487939, 6089.48239399656, 6093.9085913712215, 6139.2325959255095, 6142.9078563820685, 6146.6004709693725, 6027.638341699512, 6031.155638210742, 6039.335673076046, 6044.788432404845, 6069.393060693896, 5990.0, 6006.069059295816, 6016.518678505797, 6018.337982821974, 6021.458068773832, 4668.6498512611015, 5791.7683292667825, 5796.652200867961, 5828.70856457167, 5915.319738229987, 6008.459238668541, 6072.240969878765, 6078.6048906820915, 6091.42131497507, 6120.44915957566, 5722.0, 5768.826935191951, 5888.4700376966275, 5921.0, 5981.700914954322], "value": 5619.167565946065, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.9812807487701, 496.99751501242486, 509.55668568345544, 542.8615702995736, 552.9438771945585, 24082.638760418587, 37334.62665373349, 38416.42375364365, 38607.0, 39662.0, 39266.21467570647, 39575.604243957554, 39636.80181599088, 39653.206935861264, 39690.847918572596, 9277.304202184829, 9463.905360946406, 9471.668491602795, 9472.573734181944, 9478.81042379152, 9315.580798864034, 9411.905880941205, 9412.247020238365, 9417.340786144972, 9452.243820494345, 9369.15677589017, 9422.905770942272, 9437.95281023594, 9438.952805235964, 9453.527323633825, 9367.765805854873, 9410.62357505699, 9428.717138485856, 9440.811183776355, 9447.338686291929, 9139.908600913972, 9180.357374983687, 9205.76985575366, 9213.170814626648, 9234.1689247968, 37914.43128353077, 38245.42631860524, 38306.23387532234, 38344.616553834385, 38349.80825095884, 3206.9507127661614, 3269.29710112327, 3310.8841190558455, 3512.0, 3745.213505163919, 29211.415771684446, 29565.40869182626, 29697.70302296971, 29887.402251955056, 30785.076447706604, 2811.4517669054544, 3133.8903138390046, 3154.148379937427, 3161.1306890605038, 3317.668233176687, 477798.0, 483170.58414708055, 483421.0, 486539.0, 496220.0, 53829.461705383226, 54029.72985135128, 54089.0, 54134.0, 54152.45847541553, 33036.834815826245, 33747.83126084403, 53237.467625324025, 53294.934101317755, 53297.667558311354, 14855.851441485447, 15303.311350989232, 15304.617384565252, 15312.540623781304, 15324.23378831054, 11672.824907626393, 11788.823167652492, 11789.174757767045, 11797.764044719057, 11816.704582385337, 15924.76112858308, 16913.154342283084, 17996.460106196835, 18040.639187216184, 18054.90972545129, 7606.23937606246, 7653.617319134021, 7672.846543069108, 7680.615969201629, 7741.013378110278, 839.3620848155446, 906.8809842734481, 927.5455027036838, 990.2672022703292, 1090.6673464593216, 23587.528249434916, 25470.872645637024, 27493.86253068762, 28867.625992642974, 28867.845286188738, 838.1157878438185, 1003.6738060130341, 1013.7212266626736, 1036.6889933020216, 1037.2790910317408, 14590.708185836223, 14790.63023424423, 14819.629509262139, 15195.468158614396, 15335.846641533664, 564754.0, 655533.0, 659473.0, 664585.0, 665086.0, 53105.7344713274, 53708.46291537113, 53711.0, 53814.0, 53845.46154538483, 1160.8723040465543, 1217.6164508179895, 1252.586646406702, 1347.2859384526216, 1377.1530508737255, 22980.655290170995, 28045.597720113503, 29400.970966016088, 29624.703752963058, 30082.84958575193, 36430.635693642726, 44981.10037799291, 44983.0, 45015.32477012913, 45204.095918082116, 483388.0, 487236.56381717866, 487482.0, 487997.0, 679428.0, 37239.6276037247, 38373.23253534969, 38846.223075538896, 39014.219715606094, 39188.02029949274, 43041.569584305005, 43434.78282608567, 43729.78135109304, 43778.0, 44071.77964110287, 35902.92184258521, 36094.278114438086, 36330.4550431749, 37122.0, 37426.8128659355, 34358.15911295136, 35674.643253567134, 36840.44739328859, 37049.814750926074, 38345.80827095846, 34234.315313693085, 36548.26903461969, 36684.81657591695, 37153.62846371502, 37162.44256336103, 31882.36235275328, 33221.16947076342, 33240.667593324724, 33249.50125748164, 33452.665473344954, 24058.39854003664, 24498.265052048468, 24723.752762472144, 25078.62382064234, 25287.87356063208], "value": 19082.23067973022, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.5152162732989, 361.4127043554224, 368.56325254573335, 379.09396542263994, 398.45411785853383, 14729.852701472995, 14996.0, 15777.55447905575, 20059.699104513445, 20480.28319008835, 16873.40943066993, 19170.71243931342, 19716.0, 20306.796932030677, 20609.175632974664, 5711.286089238845, 5993.61041532299, 6006.21919150512, 6021.819345419644, 6022.337542870281, 4627.421572303462, 5948.315943666469, 6000.75996960121, 6006.969965150168, 6027.668478233695, 5584.6090773645865, 5952.880942381172, 5965.403459654043, 5966.642001479903, 5975.790847320344, 5796.62321949072, 5890.852728681817, 5898.675572843492, 5900.291964964189, 5902.203202567642, 4906.31311616374, 5046.44489106198, 5137.588992880561, 5158.200478925773, 5158.3552055993005, 24742.886570104387, 24859.62710559343, 24964.875175624005, 25164.748352516606, 25262.242132736046, 2147.194801949269, 2408.8072954163626, 2443.5235129149823, 2671.19864040787, 2799.1322689965973, 14641.653304474312, 15041.247937603077, 15096.24518774057, 15743.370265189378, 15799.894007419485, 1733.566608347913, 1847.085692582167, 1852.2128095559333, 1869.8645788830393, 1899.838513726335, 111004.44497777459, 113270.43364783206, 113627.0, 115549.0, 116271.0, 10432.426216558162, 10466.267361284637, 10647.361158330525, 10736.677899663022, 10835.349879007284, 11017.944910275397, 12993.285369304778, 13000.479980800663, 13001.869981300255, 13033.0, 8094.51432914027, 8173.18268173178, 8187.4678145920325, 8353.248207661218, 8405.285550728135, 7210.882313241456, 7952.284294413531, 8229.753107406668, 8253.422260441712, 8406.369522285853, 6220.34686357935, 6341.8097457076365, 6377.776777812847, 6384.904226436607, 6411.5832470889245, 1802.8737988340952, 1811.8459930905758, 1812.9002904840097, 1815.7730283714536, 1817.1374341300009, 865.7272959017889, 932.4591736792644, 933.6265493802398, 976.5263847034183, 1021.1473419694638, 17738.201780919888, 20435.897820511098, 21119.366419007456, 21778.34664960054, 22122.115115395525, 862.6463150108425, 903.6249956268207, 917.3395155488038, 945.522511131874, 964.5804075227298, 10991.780164396667, 11339.0, 11570.652880413601, 11624.88375116255, 11638.767224655629, 478529.607351961, 495496.0, 523851.0, 528583.0, 529415.0, 45713.0, 72778.63610681913, 113256.0, 113815.0, 114896.42551787187, 952.2382094324513, 984.3306551545019, 1007.0995532865301, 1008.1128606825946, 1025.610268098118, 18615.97612131346, 18992.0, 19161.041947902828, 19200.231990720775, 19255.8074419254, 49171.508284916694, 49985.500144998085, 50381.0, 51551.0, 51607.48392516027, 69003.0, 70694.0, 71173.64413178108, 71221.0, 72333.0, 7608.277213664693, 7718.3053525183, 7873.724419645285, 7906.0, 7989.960050199712, 11971.880281197076, 12728.6817829555, 12838.486460541473, 13069.0, 13283.601491955256, 10107.3430227035, 10227.926067763075, 10422.739431514272, 10458.477076146315, 11333.150013748836, 11010.339379637247, 11033.393163376091, 11080.944595276973, 11123.27698699582, 11594.014508766511, 11565.785592513, 11671.824922626338, 11723.824142637697, 11804.40977951082, 11889.048876090064, 6566.704498297684, 7194.316539928698, 7285.817854553678, 7360.0, 7720.7683769487, 9302.720918372459, 9337.626494940127, 9923.851142233018, 10103.292769506357, 10869.347839129678], "value": 9373.93717883184, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1339.551250331139, 1498.430596373378, 1519.6960607878423, 1655.8840881138317, 1805.241798444653, 7479.139898911627, 8099.87850182247, 8314.29328507076, 8400.49597024179, 8479.152084791518, 8193.631286592117, 8202.83594328113, 8294.668213271461, 8299.917000830004, 8312.54281014544, 2268.126771193094, 2301.8964146613444, 2306.2043594959714, 2306.411864974429, 2307.3424074138875, 2261.604219261623, 2298.9310320690324, 2318.686977258074, 2319.8492098013658, 2320.4894923116885, 2251.4596496840804, 2262.321303608919, 2283.6574513822975, 2292.701948746669, 2294.4952110535655, 2245.820334373246, 2248.3704562722382, 2274.601944659686, 2277.544491101786, 2281.7946384825445, 2264.230161745015, 2284.0863654538152, 2285.8857057147243, 2286.062714287151, 2304.055337311711, 8189.0, 8251.504909705436, 8284.958575207085, 8292.0, 8316.209960053786, 5022.723750193738, 5231.633785635007, 5237.423883372864, 5244.0, 5246.842594722164, 3439.4496880499, 3546.414841551141, 3584.6594573515476, 3613.403788374915, 3619.4027985382645, 1060.973475663107, 1062.3466568060664, 1068.631322193842, 1080.637986274599, 1083.6694808083532, 8977.640894364284, 10608.734781630445, 10610.575576976911, 10755.462226888625, 10769.78460430787, 4231.87304380869, 4328.632066274402, 4382.671299652538, 4404.625606823456, 4426.247537918497, 8403.159684031549, 8563.914360856435, 8573.957130214309, 8577.785555361166, 8582.87125693115, 4977.452480227172, 5081.110805609003, 5081.923771143436, 5082.669626474268, 5084.644074914794, 4035.757854528738, 4281.400603915455, 4415.205263052681, 4420.31485119807, 4768.356271903318, 2993.4911065118545, 3284.261041265744, 3549.8757543485854, 3599.6040435552068, 3676.1361080146144, 1136.7783282260045, 1140.2702270546856, 1141.2695874640237, 1150.0914277720508, 1151.4990978924195, 819.8811172379972, 856.4475913036057, 884.4560595234013, 926.1386910173603, 926.9907300926905, 5028.7737051832755, 5566.970110529565, 5669.518090962232, 5705.4579814917515, 5774.88450230993, 781.4998401023349, 863.3352318714554, 875.4178471316503, 884.5886662701746, 914.5564401265381, 3596.478510615946, 3625.0756057205244, 3701.2597480504005, 3742.4947632069866, 3783.2055268393947, 9721.027897210222, 10097.949510252402, 10106.039926206999, 10143.289969702199, 10156.542955567018, 10094.596216151418, 10095.091441770275, 10098.192144628412, 10100.595976161017, 10122.797544049077, 6818.863622727517, 7438.7024519019715, 7721.65252563636, 7903.525788452711, 7916.208379162038, 5471.316085489314, 5553.222548843084, 5610.270664813711, 5631.155326701028, 5655.7172141393585, 9114.0, 9252.0, 9342.299327550596, 9453.43279403238, 9481.810363792823, 7785.688572457038, 9007.504587247764, 9259.953700231456, 9575.138237558653, 9658.420494770337, 7001.649917504206, 7214.314640109181, 7229.421646268181, 7284.125904891447, 7290.05229320185, 7722.768316950501, 7886.921130788619, 7953.642086106255, 7998.280154786097, 8002.639881205476, 6048.425399587032, 7058.647067646494, 7123.038389817309, 7124.323189297009, 7160.928390716234, 6976.093107895941, 6993.335633114846, 7001.054857594159, 7045.154581450259, 7055.1886533049355, 5795.797147099831, 6595.208574971034, 6667.266600674019, 6749.257581666111, 6761.019652150509, 4658.743769092597, 4730.526947305242, 4737.597304229179, 4785.138675038526, 4902.411710594752, 4621.838235661736, 4770.689905156154, 4807.663463557517, 4824.758762061953, 4899.3385892904], "value": 4332.358414090896, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [252.4937500311875, 277.3371641776154, 294.0399595321275, 304.5401443819831, 307.64159753886725, 4000.7999600019994, 5315.734213289339, 5346.679199248043, 5348.977827527444, 5396.43337449568, 4525.932111018329, 4638.840289927518, 4937.111319962405, 5102.285680004802, 5220.564344805181, 3280.343931213755, 3460.1349662584353, 3710.0, 3972.7553932028454, 3979.283728928799, 3985.7409268397605, 4004.097029653507, 4014.1570270243283, 4019.5980401959714, 4023.577524359944, 3472.9652703472893, 3846.0, 3969.8213580388806, 3971.4837071180705, 3993.361062230044, 3353.541209573831, 3487.738419618539, 3856.6914646828186, 3985.784335777587, 4009.97995010026, 3478.617352091268, 3491.9650803491895, 3495.1611613212644, 3497.7264777894316, 3513.7013353864954, 3754.699624030071, 4299.785010749481, 4791.976040119812, 5055.292259083732, 5136.666116702402, 4048.0247018441423, 4537.3647689323525, 4562.703424277444, 4597.95402045982, 4625.213713668684, 5534.225208470817, 5538.723063846832, 5563.860903477405, 5564.888702225933, 5565.638233514808, 3242.8702851886133, 3828.4686125549724, 3848.517706079563, 3856.6914646828186, 3857.4406711027023, 5629.774809007635, 5725.5133313667975, 5771.336296325934, 5794.725160464691, 5813.040848260074, 3895.2988462077096, 3902.843886244518, 3911.3741801311653, 3919.2553414851086, 3926.430667553189, 3941.4481972523868, 3952.5256969163884, 3957.4261732049267, 3958.9208215835524, 3968.0, 3834.7123965702685, 4315.4605674290715, 4543.659225558096, 4563.315502674625, 4620.44554653437, 4419.806652203887, 4644.535546445329, 5379.870227252242, 5554.917021053273, 5565.0, 1209.9455524501418, 1286.7619490394306, 1303.328785675378, 1323.2060763541797, 1355.6678613739698, 857.6097875466647, 867.5185272174004, 877.1316396767155, 902.3322741171486, 921.7188757428914, 4017.819198136091, 4576.039031803292, 5034.697918124924, 5151.381834179922, 5224.779468725852, 5633.0, 5706.914396284059, 5724.4561766632105, 5728.570357223225, 5731.942680573141, 4417.514073451917, 4988.825391111366, 5058.418281897629, 5075.2387141929, 5191.923230707672, 2564.6665933428535, 2639.4457163995394, 2745.805574575066, 2941.9703103913457, 2954.7340739333563, 5094.77073531692, 5740.454656807597, 5910.586258961917, 5958.374370691102, 5985.850353741192, 4888.560029597354, 5208.9739551302755, 5530.949119667251, 5804.169248296104, 5815.214945982323, 5180.792768289301, 5188.028549151299, 5871.941280587225, 5878.617889837233, 5906.704664766745, 4522.8643140705835, 4614.861554153381, 5300.946990530046, 5572.972135139461, 5664.716764161858, 4507.782898617421, 4730.6215502759005, 5568.526675232489, 5603.627111357743, 5613.298337707786, 5063.518965698253, 5890.882182356415, 5904.261967254093, 5921.585489015728, 5972.20833749882, 3056.373443444133, 3105.813651180846, 3775.0, 3788.726763941723, 3801.6198380161763, 5284.837335786121, 5286.603504737083, 5738.799142030009, 5740.412242348532, 5748.511376532874, 5159.922601160911, 5579.9163012555655, 5592.972035139798, 5602.5517958562405, 5649.322081350264, 4715.585324402597, 5277.677821758414, 5498.0, 5560.304961879765, 5615.578831587565, 4545.545445455428, 4875.878992638213, 4973.328600639012, 5439.755211015435, 5497.151993604413, 5157.1208821943765, 5437.981920034419, 5507.430382341014, 5568.359638641608, 5583.771570254537, 4528.592426681483, 5458.344998600193, 5460.34475862899, 5492.972535137299, 5495.313085864267], "value": 3991.58589010946, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.9116114905063, 692.518699503845, 750.5459197185703, 786.3551887452288, 808.6644042722271, 14250.2162381069, 14799.778003329946, 14925.477608283712, 15203.391864325442, 15213.467528636485, 13443.596692099252, 13621.795673064888, 13635.386407611657, 13679.589612311622, 14166.362513686883, 817.4890693316677, 832.1512057701141, 832.6586099699125, 834.8121672623641, 835.1356346181703, 665.2715276771927, 687.1033301541486, 832.5961908474385, 835.4945258118842, 835.9205875441854, 718.0629278791201, 871.8910136232971, 888.7422647432269, 900.2662829793697, 901.8737376767258, 888.5601627194548, 890.6170346750895, 890.8084761776249, 891.0643823984855, 893.115815342806, 656.655255990604, 657.3951964192906, 657.4149007383432, 658.1378394303478, 659.4328877165634, 11875.52497900083, 12394.132410731252, 14081.014328996975, 14673.559793206221, 14694.4856930008, 10695.251332406735, 13644.317784110854, 13854.792178117335, 13937.372818223204, 14004.789928151085, 11125.777484450266, 11190.0, 11285.887141128565, 11525.596604118815, 11635.592754253561, 3713.0, 3717.8512859485586, 3740.401535754266, 3773.660370566662, 3870.5161854768153, 84255.99999999939, 84503.7324440134, 84665.19006644716, 84971.0, 85061.57469212615, 9825.410475371358, 10686.89313106859, 10759.731006724738, 10886.782264354668, 11289.71775705614, 19052.61894762097, 19907.303244386378, 21245.46886327823, 21258.0, 21265.0, 7671.079470463468, 7800.297973182441, 7898.644560994769, 7950.840983180419, 7991.560464174526, 4264.232438161161, 4274.273373526508, 4401.889952751207, 4423.203823311835, 4423.447069116361, 3405.6764607362757, 3500.7024402925526, 3509.701675357572, 3515.4199557072798, 3535.4696795480368, 656.2420404432887, 663.8274048747361, 670.4435318685479, 670.6378555579932, 675.6993138053617, 500.5420040662826, 521.1453216724575, 539.7840863654569, 549.3572520151432, 583.716594157862, 7239.529430586995, 8253.79365515867, 8762.780930476789, 9040.0, 9096.681616143404, 584.3163498706524, 615.8398816307792, 629.6808186848544, 640.276487569044, 658.4666420199653, 3502.404591219499, 4339.479262488521, 4345.565443455629, 4397.714148580332, 4457.353683715908, 97297.05405891842, 104593.90812183866, 109672.4516377413, 113232.43383783028, 113638.0, 57576.8484630305, 75487.62256188794, 76341.61829190819, 77577.63851189513, 87426.1257387418, 73480.0, 73648.0, 74258.6287068572, 77330.0, 78189.60905195438, 7928.909101789213, 8975.28197744192, 9147.634094636407, 9358.108613951883, 9647.421154730739, 49225.0, 50193.74903125461, 52130.478695212565, 52359.95280094301, 53162.0, 35722.464163038094, 40350.1929961405, 51932.74033629808, 66592.66703666645, 69766.0, 10291.691249262534, 12152.270863748203, 12323.383830808603, 12348.444320005441, 12380.62858114258, 13961.092528985584, 14072.78890816659, 14199.29003549839, 14336.56990290293, 14428.278586070865, 12334.81497777516, 12379.566715164927, 12918.547850825176, 12918.677033073873, 13278.80081798793, 11899.76200476003, 12626.368681565702, 12836.871631283568, 13018.609441716764, 13442.059055865995, 9934.503274836374, 12107.213031152947, 12372.62882113538, 12401.62795116148, 12932.741345172855, 9822.65620703272, 10076.790785105833, 10084.747881303027, 10612.628558000433, 11560.768784624091, 10435.791284174426, 10947.233693641369, 11134.554617815196, 11259.887401125883, 11317.773644527228], "value": 7069.962032556323, "unit": "score"}], "name": "dromaeo_css", "value": 7051.1436873945995, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 113.0, 113.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 113.0, 167.0, 140.0, 139.0, 124.0, 114.0, 136.0, 112.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 182.0, 187.0, 184.0, 186.0, 184.0, 181.0, 186.0, 183.0, 186.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 59.0, 89.0, 60.0, 60.0, 62.0, 61.0, 84.0, 57.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 91.0, 71.0, 68.0, 70.0, 89.0, 68.0, 70.0, 92.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 85.0, 86.0, 85.0, 89.0, 86.0, 88.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 133.0, 94.0, 95.0, 94.0, 93.0, 93.0, 95.0, 94.0, 93.0], "value": 102.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 83.0, 80.0, 85.0, 81.0, 85.0, 84.0, 81.0, 84.0], "value": 82.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 54.0, 54.0, 56.0, 56.0, 55.0, 55.0, 56.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 77.0, 76.0, 73.0, 70.0, 71.0, 69.0, 70.0, 72.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 91.0, 89.0, 86.0, 86.0, 89.0, 87.0, 84.0, 86.0, 93.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 160.0, 147.0, 147.0, 146.0, 144.0, 146.0, 146.0, 192.0, 145.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 170.0, 172.0, 174.0, 172.0, 174.0, 172.0, 171.0, 173.0, 173.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 63.0, 66.0, 66.0, 67.0, 67.0, 68.0, 66.0, 67.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1450.7, "alertThreshold": 2.0}]} 09:54:54 INFO - Return code: 0 09:54:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:54:54 INFO - # TBPL SUCCESS # 09:54:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:54:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:54:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:54:54 INFO - 2016-11-03 09:54:54.541 screenresolution[1941:15423] starting screenresolution argv=screenresolution get 09:54:54 INFO - 2016-11-03 09:54:54.544 screenresolution[1941:15423] Display 0: 1600x1200x32@60 09:54:54 INFO - 2016-11-03 09:54:54.550 screenresolution[1942:15426] starting screenresolution argv=screenresolution list 09:54:54 INFO - Available Modes on Display 0 09:54:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:54:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:54:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:54:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:54:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:54:54 INFO - Intel Iris: 09:54:54 INFO - Chipset Model: Intel Iris 09:54:54 INFO - Type: GPU 09:54:54 INFO - Bus: Built-In 09:54:54 INFO - VRAM (Dynamic, Max): 1536 MB 09:54:54 INFO - Vendor: Intel (0x8086) 09:54:54 INFO - Device ID: 0x0a2e 09:54:54 INFO - Revision ID: 0x0009 09:54:54 INFO - Displays: 09:54:54 INFO - Display: 09:54:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:54:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:54:54 INFO - Display Serial Number: 1600x1200 60 09:54:54 INFO - Main Display: Yes 09:54:54 INFO - Mirror: Off 09:54:54 INFO - Online: Yes 09:54:54 INFO - Rotation: Supported 09:54:54 INFO - Return code: 0 09:54:54 INFO - Running post-action listener: _resource_record_post_action 09:54:54 INFO - [mozharness: 2016-11-03 16:54:54.793605Z] Finished run-tests step (success) 09:54:54 INFO - Running post-run listener: _resource_record_post_run 09:54:54 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 1716224; Write bytes: 707108864; Read time: 12; Write time: 2010 09:54:54 INFO - TinderboxPrint: CPU usage
53.5% 09:54:54 INFO - TinderboxPrint: I/O read bytes / time
1,716,224 / 12 09:54:54 INFO - TinderboxPrint: I/O write bytes / time
707,108,864 / 2,010 09:54:54 INFO - TinderboxPrint: CPU idle
2,258.6 (46.5%) 09:54:54 INFO - TinderboxPrint: CPU system
968.5 (20.0%) 09:54:54 INFO - TinderboxPrint: CPU user
1,626.6 (33.5%) 09:54:54 INFO - TinderboxPrint: Swap in / out
407,908,352 / 0 09:54:54 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390328832; Write bytes: 384689664; Read time: 29061; Write time: 831 09:54:54 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 1716224; Write bytes: 298584576; Read time: 12; Write time: 1119 09:54:55 INFO - Running post-run listener: _upload_blobber_files 09:54:55 INFO - Blob upload gear active. 09:54:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:54:55 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:54:55 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:54:55 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:54:55 INFO - (blobuploader) - INFO - Open directory for files ... 09:54:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:54:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:54:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:56 INFO - (blobuploader) - INFO - Done attempting. 09:54:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:54:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:54:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:57 INFO - (blobuploader) - INFO - Done attempting. 09:54:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:54:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:55:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:01 INFO - (blobuploader) - INFO - Done attempting. 09:55:01 INFO - (blobuploader) - INFO - Iteration through files over. 09:55:01 INFO - Return code: 0 09:55:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:55:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:55:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d30eabd2e5c4dff41ef9027f9ab11f944e6ac89316c5f1fa95e7acf32bd1260250d6c956332409bc345fc93159a0bfa567c6991146f84e4e3e324f078aaf6d73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eeab1bb5b58d7026a5b23d87fdd32c0a1002b3c08ab5e442b3bc257f4b44ff9d492c5f828783ded70e1b03354e7af0aca79e4e3c24336995ac88edf347348083", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a893997f4b2ec62f27205e03103136983505ded283f2ef2f8d0a5f4e723b2cc87e3f00014a9f60c5c43ab2b88b8fe3724139775ee70b1a6e824e3d21ce15a1ea"} 09:55:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:55:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:55:01 INFO - Contents: 09:55:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d30eabd2e5c4dff41ef9027f9ab11f944e6ac89316c5f1fa95e7acf32bd1260250d6c956332409bc345fc93159a0bfa567c6991146f84e4e3e324f078aaf6d73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eeab1bb5b58d7026a5b23d87fdd32c0a1002b3c08ab5e442b3bc257f4b44ff9d492c5f828783ded70e1b03354e7af0aca79e4e3c24336995ac88edf347348083", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a893997f4b2ec62f27205e03103136983505ded283f2ef2f8d0a5f4e723b2cc87e3f00014a9f60c5c43ab2b88b8fe3724139775ee70b1a6e824e3d21ce15a1ea"} 09:55:01 INFO - Running post-run listener: copy_logs_to_upload_dir 09:55:01 INFO - Copying logs to upload dir... 09:55:01 INFO - mkdir: /builds/slave/test/build/upload/logs 09:55:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.392776 ========= master_lag: 0.89 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-03 09:55:02.519371) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-03 09:55:02.523802) ========= 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.8aavnU58dK/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.7TR786NQ00/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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/d30eabd2e5c4dff41ef9027f9ab11f944e6ac89316c5f1fa95e7acf32bd1260250d6c956332409bc345fc93159a0bfa567c6991146f84e4e3e324f078aaf6d73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eeab1bb5b58d7026a5b23d87fdd32c0a1002b3c08ab5e442b3bc257f4b44ff9d492c5f828783ded70e1b03354e7af0aca79e4e3c24336995ac88edf347348083", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a893997f4b2ec62f27205e03103136983505ded283f2ef2f8d0a5f4e723b2cc87e3f00014a9f60c5c43ab2b88b8fe3724139775ee70b1a6e824e3d21ce15a1ea"} build_url:https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010755 build_url: 'https://queue.taskcluster.net/v1/task/A-hk7bvuTnWbKRgRyAVXeg/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/d30eabd2e5c4dff41ef9027f9ab11f944e6ac89316c5f1fa95e7acf32bd1260250d6c956332409bc345fc93159a0bfa567c6991146f84e4e3e324f078aaf6d73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eeab1bb5b58d7026a5b23d87fdd32c0a1002b3c08ab5e442b3bc257f4b44ff9d492c5f828783ded70e1b03354e7af0aca79e4e3c24336995ac88edf347348083", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a893997f4b2ec62f27205e03103136983505ded283f2ef2f8d0a5f4e723b2cc87e3f00014a9f60c5c43ab2b88b8fe3724139775ee70b1a6e824e3d21ce15a1ea"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-03 09:55:02.563892) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 09:55:02.564218) ========= 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.8aavnU58dK/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.7TR786NQ00/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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.005019 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 09:55:02.617105) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-03 09:55:02.618867) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-03 09:55:02.619178) ========= ========= Total master_lag: 2.23 =========