builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0108 starttime: 1468932264.99 results: success (0) buildid: 20160719030224 builduid: d8a10a6df2a44252acc4188e3048ce61 revision: feaaf1af1065257b9178faca8b67eed9657b4a17 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 05:44:24.987631) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 05:44:24.988157) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 05:44:24.988478) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 05:44:25.014368) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 05:44:25.014802) ========= 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.PBmqlZHLaN/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.05wQR2NITL/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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-07-19 05:44:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.79M=0.001s 2016-07-19 05:44:25 (9.79 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085033 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 05:44:25.123115) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 05:44:25.123440) ========= 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.PBmqlZHLaN/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.05wQR2NITL/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.028064 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 05:44:25.172047) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 05:44:25.172403) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev feaaf1af1065257b9178faca8b67eed9657b4a17 --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 feaaf1af1065257b9178faca8b67eed9657b4a17 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PBmqlZHLaN/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.05wQR2NITL/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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-07-19 05:44:25,246 truncating revision to first 12 chars 2016-07-19 05:44:25,246 Setting DEBUG logging. 2016-07-19 05:44:25,247 attempt 1/10 2016-07-19 05:44:25,247 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/feaaf1af1065?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 05:44:26,870 unpacking tar archive at: mozilla-central-feaaf1af1065/testing/mozharness/ program finished with exit code 0 elapsedTime=1.882332 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 05:44:27.076750) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 05:44:27.077120) ========= script_repo_revision: feaaf1af1065257b9178faca8b67eed9657b4a17 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 05:44:27.077513) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 05:44:27.077773) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 05:44:27.096146) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-19 05:44:27.096524) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PBmqlZHLaN/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.05wQR2NITL/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 05:44:27 INFO - MultiFileLogger online at 20160719 05:44:27 in /builds/slave/test 05:44:27 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 05:44:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:44:27 INFO - {'append_to_log': False, 05:44:27 INFO - 'base_work_dir': '/builds/slave/test', 05:44:27 INFO - 'blob_upload_branch': 'Firefox', 05:44:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:44:27 INFO - 'branch': 'Firefox', 05:44:27 INFO - 'buildbot_json_path': 'buildprops.json', 05:44:27 INFO - 'config_files': ('talos/mac_config.py',), 05:44:27 INFO - 'default_actions': ('clobber', 05:44:27 INFO - 'read-buildbot-config', 05:44:27 INFO - 'download-and-extract', 05:44:27 INFO - 'populate-webroot', 05:44:27 INFO - 'create-virtualenv', 05:44:27 INFO - 'install', 05:44:27 INFO - 'run-tests'), 05:44:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:44:27 INFO - 'download_minidump_stackwalk': True, 05:44:27 INFO - 'download_symbols': 'ondemand', 05:44:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:44:27 INFO - 'tooltool.py': '/tools/tooltool.py', 05:44:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:44:27 INFO - '/tools/misc-python/virtualenv.py')}, 05:44:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:44:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:44:27 INFO - 'installer_path': 'installer.exe', 05:44:27 INFO - 'log_level': 'info', 05:44:27 INFO - 'log_name': 'talos', 05:44:27 INFO - 'log_to_console': True, 05:44:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:44:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:44:27 INFO - 'opt_config_files': (), 05:44:27 INFO - 'pip_index': False, 05:44:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:44:27 INFO - 'cmd': ('bash', 05:44:27 INFO - '-c', 05:44:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:44:27 INFO - 'enabled': True, 05:44:27 INFO - 'halt_on_failure': False, 05:44:27 INFO - 'name': 'check_screen_resolution'},), 05:44:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:44:27 INFO - 'cmd': ('bash', 05:44:27 INFO - '-c', 05:44:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:44:27 INFO - 'enabled': True, 05:44:27 INFO - 'halt_on_failure': False, 05:44:27 INFO - 'name': 'check_screen_resolution'},), 05:44:27 INFO - 'run_cmd_checks_enabled': True, 05:44:27 INFO - 'sps_profile': False, 05:44:27 INFO - 'sps_profile_interval': 0, 05:44:27 INFO - 'suite': 'dromaeojs', 05:44:27 INFO - 'system_bits': '32', 05:44:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:44:27 INFO - 'title': 't-yosemite-r7-0108', 05:44:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:44:27 INFO - 'use_talos_json': True, 05:44:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:44:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:44:27 INFO - 'work_dir': 'build'} 05:44:27 INFO - ##### 05:44:27 INFO - ##### Running clobber step. 05:44:27 INFO - ##### 05:44:27 INFO - Running pre-action listener: _resource_record_pre_action 05:44:27 INFO - Running main action method: clobber 05:44:27 INFO - rmtree: /builds/slave/test/build 05:44:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:44:29 INFO - Running post-action listener: _resource_record_post_action 05:44:29 INFO - ##### 05:44:29 INFO - ##### Finished clobber step (success) 05:44:29 INFO - ##### 05:44:29 INFO - ##### 05:44:29 INFO - ##### Running read-buildbot-config step. 05:44:29 INFO - ##### 05:44:29 INFO - Running pre-action listener: _resource_record_pre_action 05:44:29 INFO - Running main action method: read_buildbot_config 05:44:29 INFO - Using buildbot properties: 05:44:29 INFO - { 05:44:29 INFO - "project": "", 05:44:29 INFO - "product": "firefox", 05:44:29 INFO - "script_repo_revision": "production", 05:44:29 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:44:29 INFO - "repository": "", 05:44:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:44:29 INFO - "buildid": "20160719030224", 05:44:29 INFO - "stage_platform": "macosx64", 05:44:29 INFO - "basedir": "/builds/slave/test", 05:44:29 INFO - "nightly_build": "True", 05:44:29 INFO - "buildnumber": 297, 05:44:29 INFO - "slavename": "t-yosemite-r7-0108", 05:44:29 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:44:29 INFO - "platform": "yosemite_r7", 05:44:29 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:44:29 INFO - "branch": "mozilla-central", 05:44:29 INFO - "repo_path": "mozilla-central", 05:44:29 INFO - "revision": "feaaf1af1065257b9178faca8b67eed9657b4a17", 05:44:29 INFO - "pgo_build": "False", 05:44:29 INFO - "builduid": "d8a10a6df2a44252acc4188e3048ce61", 05:44:29 INFO - "slavebuilddir": "test" 05:44:29 INFO - } 05:44:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 05:44:29 INFO - Running post-action listener: _resource_record_post_action 05:44:29 INFO - ##### 05:44:29 INFO - ##### Finished read-buildbot-config step (success) 05:44:29 INFO - ##### 05:44:29 INFO - ##### 05:44:29 INFO - ##### Running download-and-extract step. 05:44:29 INFO - ##### 05:44:29 INFO - Running pre-action listener: _resource_record_pre_action 05:44:29 INFO - Running main action method: download_and_extract 05:44:29 INFO - mkdir: /builds/slave/test/build/tests 05:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:29 INFO - https://queue.taskcluster.net/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:44:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:44:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:44:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:44:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 05:44:30 INFO - Downloaded 1199 bytes. 05:44:30 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:44:30 INFO - Using the following test package requirements: 05:44:30 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:44:30 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:44:30 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 05:44:30 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:44:30 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 05:44:30 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:44:30 INFO - u'jsshell-mac.zip'], 05:44:30 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:44:30 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 05:44:30 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:44:30 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:44:30 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 05:44:30 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:44:30 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 05:44:30 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:44:30 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 05:44:30 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:44:30 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 05:44:30 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:30 INFO - https://queue.taskcluster.net/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:44:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:44:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:44:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:44:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:44:32 INFO - Downloaded 18835520 bytes. 05:44:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:44:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:44:35 INFO - Return code: 0 05:44:35 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:35 INFO - https://queue.taskcluster.net/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:44:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:44:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:44:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:44:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:44:36 INFO - Downloaded 18835520 bytes. 05:44:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:44:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:44:40 INFO - Return code: 0 05:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:40 INFO - https://queue.taskcluster.net/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:44:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:44:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:44:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:44:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:44:41 INFO - Downloaded 14403317 bytes. 05:44:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:44:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:44:41 INFO - Return code: 0 05:44:41 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:41 INFO - https://queue.taskcluster.net/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:44:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:44:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:44:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:44:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 05:44:44 INFO - Downloaded 113026021 bytes. 05:44:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:44:44 INFO - mkdir: /builds/slave/test/properties 05:44:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:44:44 INFO - Writing to file /builds/slave/test/properties/build_url 05:44:44 INFO - Contents: 05:44:44 INFO - build_url:https://queue.taskcluster.net/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:44:46 INFO - Running post-action listener: _resource_record_post_action 05:44:46 INFO - Running post-action listener: set_extra_try_arguments 05:44:46 INFO - ##### 05:44:46 INFO - ##### Finished download-and-extract step (success) 05:44:46 INFO - ##### 05:44:46 INFO - ##### 05:44:46 INFO - ##### Running populate-webroot step. 05:44:46 INFO - ##### 05:44:46 INFO - Running pre-action listener: _resource_record_pre_action 05:44:46 INFO - Running main action method: populate_webroot 05:44:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:44:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:44:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:44:46 INFO - '--tppagecycles', 05:44:46 INFO - '7'], 05:44:46 INFO - 'tests': ['tsvgm']}}, 05:44:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:44:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:44:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:44:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:44:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:44:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:44:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:46 INFO - 'talos_options': ['--disable-e10s'], 05:44:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:44:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:44:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:46 INFO - 'talos_options': ['--disable-e10s'], 05:44:46 INFO - 'tests': ['damp', 'tps']}, 05:44:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:46 INFO - 'tests': ['damp', 'tps']}, 05:44:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:44:46 INFO - 'tests': ['dromaeo_dom']}, 05:44:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:44:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:44:46 INFO - 'tests': ['basic_compositor_video']}, 05:44:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:44:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:44:46 INFO - 'tests': ['a11yr', 05:44:46 INFO - 'ts_paint', 05:44:46 INFO - 'tpaint', 05:44:46 INFO - 'sessionrestore', 05:44:46 INFO - 'sessionrestore_no_auto_restore', 05:44:46 INFO - 'tabpaint']}, 05:44:46 INFO - 'other-e10s': {'tests': ['a11yr', 05:44:46 INFO - 'ts_paint', 05:44:46 INFO - 'tpaint', 05:44:46 INFO - 'sessionrestore', 05:44:46 INFO - 'sessionrestore_no_auto_restore', 05:44:46 INFO - 'tabpaint']}, 05:44:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:44:46 INFO - 'ts_paint', 05:44:46 INFO - 'tpaint', 05:44:46 INFO - 'sessionrestore', 05:44:46 INFO - 'sessionrestore_no_auto_restore', 05:44:46 INFO - 'tabpaint']}, 05:44:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:44:46 INFO - 'ts_paint', 05:44:46 INFO - 'tpaint', 05:44:46 INFO - 'sessionrestore', 05:44:46 INFO - 'sessionrestore_no_auto_restore', 05:44:46 INFO - 'tabpaint']}, 05:44:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:44:46 INFO - 'tests': ['a11yr', 05:44:46 INFO - 'ts_paint', 05:44:46 INFO - 'tpaint', 05:44:46 INFO - 'sessionrestore', 05:44:46 INFO - 'sessionrestore_no_auto_restore', 05:44:46 INFO - 'tabpaint']}, 05:44:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:44:46 INFO - 'tests': ['a11yr', 05:44:46 INFO - 'ts_paint', 05:44:46 INFO - 'tpaint', 05:44:46 INFO - 'sessionrestore', 05:44:46 INFO - 'sessionrestore_no_auto_restore', 05:44:46 INFO - 'tabpaint']}, 05:44:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:44:46 INFO - 'tests': ['tsvgx', 05:44:46 INFO - 'tsvgr_opacity', 05:44:46 INFO - 'tart', 05:44:46 INFO - 'tscrollx', 05:44:46 INFO - 'cart']}, 05:44:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:44:46 INFO - 'tsvgr_opacity', 05:44:46 INFO - 'tart', 05:44:46 INFO - 'tscrollx', 05:44:46 INFO - 'cart']}, 05:44:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:46 INFO - 'talos_options': ['--disable-e10s'], 05:44:46 INFO - 'tests': ['tp5o']}, 05:44:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:46 INFO - 'tests': ['tp5o']}, 05:44:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:46 INFO - 'talos_options': ['--disable-e10s', 05:44:46 INFO - '--xperf_path', 05:44:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:44:46 INFO - 'tests': ['tp5n']}, 05:44:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:46 INFO - 'talos_options': ['--xperf_path', 05:44:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:44:46 INFO - 'tests': ['tp5n']}}, 05:44:46 INFO - 'talos.zip': {'path': '', 05:44:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:44:46 INFO - Running post-action listener: _resource_record_post_action 05:44:46 INFO - ##### 05:44:46 INFO - ##### Finished populate-webroot step (success) 05:44:46 INFO - ##### 05:44:46 INFO - ##### 05:44:46 INFO - ##### Running create-virtualenv step. 05:44:46 INFO - ##### 05:44:46 INFO - Running pre-action listener: _resource_record_pre_action 05:44:46 INFO - Running main action method: create_virtualenv 05:44:46 INFO - Creating virtualenv /builds/slave/test/build/venv 05:44:46 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 05:44:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:44:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:44:46 INFO - Using real prefix '/tools/python27' 05:44:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:44:46 INFO - Installing distribute.............................................................................................................................................................................................done. 05:44:49 INFO - Installing pip.................done. 05:44:49 INFO - Return code: 0 05:44:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:44:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:49 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 0x10202ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fbd9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8fd30>, '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 0x101cf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f87b20>, '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.PBmqlZHLaN/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.05wQR2NITL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 05:44:49 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 05:44:49 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 05:44:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PBmqlZHLaN/Render', 05:44:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:49 INFO - 'HOME': '/Users/cltbld', 05:44:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:49 INFO - 'LOGNAME': 'cltbld', 05:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:49 INFO - 'MOZ_NO_REMOTE': '1', 05:44:49 INFO - 'NO_EM_RESTART': '1', 05:44:49 INFO - 'PAGER': '/bin/cat', 05:44:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:49 INFO - 'PWD': '/builds/slave/test', 05:44:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:49 INFO - 'SHELL': '/bin/bash', 05:44:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.05wQR2NITL/Listeners', 05:44:49 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 05:44:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:49 INFO - 'USER': 'cltbld', 05:44:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:49 INFO - 'XPC_FLAGS': '0x0', 05:44:49 INFO - 'XPC_SERVICE_NAME': '0', 05:44:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:50 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 05:44:50 INFO - Cleaning up... 05:44:50 INFO - Return code: 0 05:44:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:44:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:50 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 0x10202ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fbd9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8fd30>, '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 0x101cf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f87b20>, '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.PBmqlZHLaN/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.05wQR2NITL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 05:44:50 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 05:44:50 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 05:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PBmqlZHLaN/Render', 05:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:50 INFO - 'HOME': '/Users/cltbld', 05:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:50 INFO - 'LOGNAME': 'cltbld', 05:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:50 INFO - 'MOZ_NO_REMOTE': '1', 05:44:50 INFO - 'NO_EM_RESTART': '1', 05:44:50 INFO - 'PAGER': '/bin/cat', 05:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:50 INFO - 'PWD': '/builds/slave/test', 05:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:50 INFO - 'SHELL': '/bin/bash', 05:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.05wQR2NITL/Listeners', 05:44:50 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 05:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:50 INFO - 'USER': 'cltbld', 05:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:50 INFO - 'XPC_FLAGS': '0x0', 05:44:50 INFO - 'XPC_SERVICE_NAME': '0', 05:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:50 INFO - Downloading/unpacking psutil>=3.1.1 05:44:50 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 05:44:50 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 05:44:50 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 05:44:50 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 05:44:50 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 05:44:50 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 05:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:44:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:44:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:44:53 INFO - Installing collected packages: psutil 05:44:53 INFO - Running setup.py install for psutil 05:44:53 INFO - building 'psutil._psutil_osx' extension 05:44:53 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 05:44:54 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 05:44:54 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 05:44:54 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 05:44:54 INFO - building 'psutil._psutil_posix' extension 05:44:54 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 05:44:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:44:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:44:54 INFO - ^ 05:44:54 INFO - 1 warning generated. 05:44:54 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 05:44:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:44:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:44:54 INFO - Successfully installed psutil 05:44:54 INFO - Cleaning up... 05:44:54 INFO - Return code: 0 05:44:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:54 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 0x10202ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fbd9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8fd30>, '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 0x101cf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f87b20>, '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.PBmqlZHLaN/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.05wQR2NITL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 05:44:54 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 05:44:54 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 05:44:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PBmqlZHLaN/Render', 05:44:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:54 INFO - 'HOME': '/Users/cltbld', 05:44:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:54 INFO - 'LOGNAME': 'cltbld', 05:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:54 INFO - 'MOZ_NO_REMOTE': '1', 05:44:54 INFO - 'NO_EM_RESTART': '1', 05:44:54 INFO - 'PAGER': '/bin/cat', 05:44:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:54 INFO - 'PWD': '/builds/slave/test', 05:44:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:54 INFO - 'SHELL': '/bin/bash', 05:44:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.05wQR2NITL/Listeners', 05:44:54 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 05:44:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:54 INFO - 'USER': 'cltbld', 05:44:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:54 INFO - 'XPC_FLAGS': '0x0', 05:44:54 INFO - 'XPC_SERVICE_NAME': '0', 05:44:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:44:55 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 05:44:55 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 05:44:55 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 05:44:55 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 05:44:55 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 05:44:55 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 05:44:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:44:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:44:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:44:57 INFO - Installing collected packages: mozsystemmonitor 05:44:57 INFO - Running setup.py install for mozsystemmonitor 05:44:57 INFO - Successfully installed mozsystemmonitor 05:44:57 INFO - Cleaning up... 05:44:57 INFO - Return code: 0 05:44:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:44:57 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:57 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:57 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 0x10202ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fbd9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8fd30>, '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 0x101cf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f87b20>, '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.PBmqlZHLaN/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.05wQR2NITL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 05:44:57 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 05:44:57 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 05:44:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PBmqlZHLaN/Render', 05:44:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:57 INFO - 'HOME': '/Users/cltbld', 05:44:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:57 INFO - 'LOGNAME': 'cltbld', 05:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:57 INFO - 'MOZ_NO_REMOTE': '1', 05:44:57 INFO - 'NO_EM_RESTART': '1', 05:44:57 INFO - 'PAGER': '/bin/cat', 05:44:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:57 INFO - 'PWD': '/builds/slave/test', 05:44:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:57 INFO - 'SHELL': '/bin/bash', 05:44:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.05wQR2NITL/Listeners', 05:44:57 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 05:44:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:57 INFO - 'USER': 'cltbld', 05:44:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:57 INFO - 'XPC_FLAGS': '0x0', 05:44:57 INFO - 'XPC_SERVICE_NAME': '0', 05:44:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:58 INFO - Downloading/unpacking jsonschema==2.5.1 05:44:58 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 05:44:58 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 05:44:58 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 05:44:58 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 05:44:58 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 05:44:58 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 05:45:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:45:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:00 INFO - Downloading functools32-3.2.3-2.tar.gz 05:45:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:45:01 INFO - warning: no files found matching '*.txt' 05:45:01 INFO - no previously-included directories found matching 'build' 05:45:01 INFO - no previously-included directories found matching 'dist' 05:45:01 INFO - no previously-included directories found matching '.git*' 05:45:01 INFO - Installing collected packages: jsonschema, functools32 05:45:01 INFO - Running setup.py install for functools32 05:45:01 INFO - warning: no files found matching '*.txt' 05:45:01 INFO - no previously-included directories found matching 'build' 05:45:01 INFO - no previously-included directories found matching 'dist' 05:45:01 INFO - no previously-included directories found matching '.git*' 05:45:01 INFO - Successfully installed jsonschema functools32 05:45:01 INFO - Cleaning up... 05:45:01 INFO - Return code: 0 05:45:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:45:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:01 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 0x10202ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fbd9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8fd30>, '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 0x101cf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f87b20>, '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.PBmqlZHLaN/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.05wQR2NITL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 05:45:01 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 05:45:01 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 05:45:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PBmqlZHLaN/Render', 05:45:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:01 INFO - 'HOME': '/Users/cltbld', 05:45:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:01 INFO - 'LOGNAME': 'cltbld', 05:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:01 INFO - 'MOZ_NO_REMOTE': '1', 05:45:01 INFO - 'NO_EM_RESTART': '1', 05:45:01 INFO - 'PAGER': '/bin/cat', 05:45:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:01 INFO - 'PWD': '/builds/slave/test', 05:45:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:01 INFO - 'SHELL': '/bin/bash', 05:45:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.05wQR2NITL/Listeners', 05:45:01 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 05:45:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:01 INFO - 'USER': 'cltbld', 05:45:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:01 INFO - 'XPC_FLAGS': '0x0', 05:45:01 INFO - 'XPC_SERVICE_NAME': '0', 05:45:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:01 INFO - Downloading/unpacking blobuploader==1.2.4 05:45:01 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 05:45:01 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 05:45:01 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 05:45:01 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 05:45:01 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 05:45:01 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 05:45:04 INFO - Downloading blobuploader-1.2.4.tar.gz 05:45:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:45:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:45:04 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 05:45:04 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 05:45:04 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 05:45:04 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 05:45:04 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 05:45:04 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 05:45:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:45:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:45:04 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 05:45:04 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 05:45:04 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 05:45:04 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 05:45:04 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 05:45:04 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 05:45:05 INFO - Downloading docopt-0.6.1.tar.gz 05:45:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:45:05 INFO - Installing collected packages: blobuploader, requests, docopt 05:45:05 INFO - Running setup.py install for blobuploader 05:45:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:45:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:45:05 INFO - Running setup.py install for requests 05:45:05 INFO - Running setup.py install for docopt 05:45:06 INFO - Successfully installed blobuploader requests docopt 05:45:06 INFO - Cleaning up... 05:45:06 INFO - Return code: 0 05:45:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:45:06 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:06 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:06 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 0x10202ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fbd9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8fd30>, '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 0x101cf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f87b20>, '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.PBmqlZHLaN/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.05wQR2NITL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 05:45:06 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 05:45:06 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 05:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PBmqlZHLaN/Render', 05:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:06 INFO - 'HOME': '/Users/cltbld', 05:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:06 INFO - 'LOGNAME': 'cltbld', 05:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:06 INFO - 'MOZ_NO_REMOTE': '1', 05:45:06 INFO - 'NO_EM_RESTART': '1', 05:45:06 INFO - 'PAGER': '/bin/cat', 05:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:06 INFO - 'PWD': '/builds/slave/test', 05:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:06 INFO - 'SHELL': '/bin/bash', 05:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.05wQR2NITL/Listeners', 05:45:06 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 05:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:06 INFO - 'USER': 'cltbld', 05:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:06 INFO - 'XPC_FLAGS': '0x0', 05:45:06 INFO - 'XPC_SERVICE_NAME': '0', 05:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:45:06 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-ndN25z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:45:06 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-B005zB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:45:06 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-mZJH7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:45:06 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-6AOGKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:45:06 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-gGtZdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:45:06 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-SwWVK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:45:07 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-L5LSIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:45:07 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-2NWLK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:45:07 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-szbTLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:45:07 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-yk57lZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:45:07 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-2EpGV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:45:07 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Dov9LE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:45:07 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-kce0sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:45:07 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-x8IjEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:07 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-GoAh1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:45:08 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-0LeqJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:45:08 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-pwCN1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:45:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:45:08 INFO - Running setup.py install for manifestparser 05:45:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:45:08 INFO - Running setup.py install for mozcrash 05:45:08 INFO - Running setup.py install for mozdebug 05:45:08 INFO - Running setup.py install for mozdevice 05:45:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:45:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:45:08 INFO - Running setup.py install for mozfile 05:45:08 INFO - Running setup.py install for mozhttpd 05:45:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:45:09 INFO - Running setup.py install for mozinfo 05:45:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:45:09 INFO - Running setup.py install for mozInstall 05:45:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:45:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:45:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:45:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:45:09 INFO - Running setup.py install for mozleak 05:45:09 INFO - Running setup.py install for mozlog 05:45:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:45:09 INFO - Running setup.py install for moznetwork 05:45:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:45:09 INFO - Running setup.py install for mozprocess 05:45:10 INFO - Running setup.py install for mozprofile 05:45:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:45:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:45:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:45:10 INFO - Running setup.py install for mozrunner 05:45:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:45:10 INFO - Running setup.py install for mozscreenshot 05:45:10 INFO - Running setup.py install for moztest 05:45:10 INFO - Running setup.py install for mozversion 05:45:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:45:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:45:10 INFO - Cleaning up... 05:45:10 INFO - Return code: 0 05:45:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:10 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 0x10202ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fbd9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8fd30>, '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 0x101cf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f87b20>, '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.PBmqlZHLaN/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.05wQR2NITL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 05:45:10 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 05:45:10 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 05:45:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PBmqlZHLaN/Render', 05:45:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:10 INFO - 'HOME': '/Users/cltbld', 05:45:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:10 INFO - 'LOGNAME': 'cltbld', 05:45:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:10 INFO - 'MOZ_NO_REMOTE': '1', 05:45:10 INFO - 'NO_EM_RESTART': '1', 05:45:10 INFO - 'PAGER': '/bin/cat', 05:45:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:10 INFO - 'PWD': '/builds/slave/test', 05:45:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:10 INFO - 'SHELL': '/bin/bash', 05:45:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.05wQR2NITL/Listeners', 05:45:10 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 05:45:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:10 INFO - 'USER': 'cltbld', 05:45:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:10 INFO - 'XPC_FLAGS': '0x0', 05:45:10 INFO - 'XPC_SERVICE_NAME': '0', 05:45:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:45:11 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-e1KYTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:45:11 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)) 05:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:45:11 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-O_sF90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:45:11 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)) 05:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:45:11 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-inCqOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:45:11 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)) 05:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:45:11 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-QmY59U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:45:11 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)) 05:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:45:11 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-cE_iIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:45:11 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)) 05:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:45:11 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-SXM3_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:45:11 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)) 05:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:45:11 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-pQVkKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:45:11 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)) 05:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:45:11 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-L6A6YW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:45:11 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)) 05:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:45:11 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-DefTG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:45:12 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)) 05:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:45:12 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-PWG9hF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:45:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 05:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:45:12 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Z8vq80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:45:12 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)) 05:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:45:12 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-k0IzY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:45:12 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)) 05:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:45:12 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-UJTYLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:45:12 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)) 05:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:45:12 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-LZvEZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:45:12 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)) 05:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:12 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-1zdG7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:12 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)) 05:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:45:12 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-4iyvBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:45:12 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)) 05:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:45:12 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-sSdDDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:45:12 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)) 05:45:12 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)) 05:45:12 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)) 05:45:12 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)) 05:45:12 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)) 05:45:12 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)) 05:45:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:45: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 05:45: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 05:45: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 05:45: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 05:45: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 05:45: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 05:45:15 INFO - Downloading blessings-1.6.tar.gz 05:45:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:45:15 INFO - Installing collected packages: blessings 05:45:15 INFO - Running setup.py install for blessings 05:45:15 INFO - Successfully installed blessings 05:45:15 INFO - Cleaning up... 05:45:15 INFO - Return code: 0 05:45:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:45:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:45:15 INFO - Reading from file tmpfile_stdout 05:45:15 INFO - Current package versions: 05:45:15 INFO - blessings == 1.6 05:45:15 INFO - blobuploader == 1.2.4 05:45:15 INFO - docopt == 0.6.1 05:45:15 INFO - functools32 == 3.2.3-2 05:45:15 INFO - jsonschema == 2.5.1 05:45:15 INFO - manifestparser == 1.1 05:45:15 INFO - mozInstall == 1.12 05:45:15 INFO - mozcrash == 0.17 05:45:15 INFO - mozdebug == 0.1 05:45:15 INFO - mozdevice == 0.48 05:45:15 INFO - mozfile == 1.2 05:45:15 INFO - mozhttpd == 0.7 05:45:15 INFO - mozinfo == 0.9 05:45:15 INFO - mozleak == 0.1 05:45:15 INFO - mozlog == 3.2 05:45:15 INFO - moznetwork == 0.27 05:45:15 INFO - mozprocess == 0.23 05:45:15 INFO - mozprofile == 0.28 05:45:15 INFO - mozrunner == 6.12 05:45:15 INFO - mozscreenshot == 0.1 05:45:15 INFO - mozsystemmonitor == 0.3 05:45:15 INFO - moztest == 0.7 05:45:15 INFO - mozversion == 1.4 05:45:15 INFO - psutil == 3.1.1 05:45:15 INFO - requests == 1.2.3 05:45:15 INFO - wsgiref == 0.1.2 05:45:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:45: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:15 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 0x10202ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fbd9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8fd30>, '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 0x101cf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f87b20>, '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.PBmqlZHLaN/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.05wQR2NITL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 05:45:15 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 05:45:15 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 05:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PBmqlZHLaN/Render', 05:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:15 INFO - 'HOME': '/Users/cltbld', 05:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:15 INFO - 'LOGNAME': 'cltbld', 05:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:15 INFO - 'MOZ_NO_REMOTE': '1', 05:45:15 INFO - 'NO_EM_RESTART': '1', 05:45:15 INFO - 'PAGER': '/bin/cat', 05:45:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:15 INFO - 'PWD': '/builds/slave/test', 05:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:15 INFO - 'SHELL': '/bin/bash', 05:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.05wQR2NITL/Listeners', 05:45:15 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 05:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:15 INFO - 'USER': 'cltbld', 05:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:15 INFO - 'XPC_FLAGS': '0x0', 05:45:15 INFO - 'XPC_SERVICE_NAME': '0', 05:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:16 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)) 05:45:16 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)) 05:45:16 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)) 05:45:16 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)) 05:45:16 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)) 05:45:16 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)) 05:45:16 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)) 05:45:16 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)) 05:45:16 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)) 05:45:16 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)) 05:45:16 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)) 05:45:16 INFO - Cleaning up... 05:45:16 INFO - Return code: 0 05:45:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:45: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10202ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fbd9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8fd30>, '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 0x101cf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f87b20>, '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.PBmqlZHLaN/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.05wQR2NITL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 05:45: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', 'jsonschema'] in /builds/slave/test/build 05:45: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 jsonschema 05:45:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PBmqlZHLaN/Render', 05:45:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:16 INFO - 'HOME': '/Users/cltbld', 05:45:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:16 INFO - 'LOGNAME': 'cltbld', 05:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:16 INFO - 'MOZ_NO_REMOTE': '1', 05:45:16 INFO - 'NO_EM_RESTART': '1', 05:45:16 INFO - 'PAGER': '/bin/cat', 05:45:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:16 INFO - 'PWD': '/builds/slave/test', 05:45:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:16 INFO - 'SHELL': '/bin/bash', 05:45:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.05wQR2NITL/Listeners', 05:45:16 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 05:45:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:16 INFO - 'USER': 'cltbld', 05:45:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:16 INFO - 'XPC_FLAGS': '0x0', 05:45:16 INFO - 'XPC_SERVICE_NAME': '0', 05:45:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:45:16 INFO - Cleaning up... 05:45:16 INFO - Return code: 0 05:45:16 INFO - Running post-action listener: _resource_record_post_action 05:45:16 INFO - Running post-action listener: _start_resource_monitoring 05:45:16 INFO - Starting resource monitoring. 05:45:16 INFO - ##### 05:45:16 INFO - ##### Finished create-virtualenv step (success) 05:45:16 INFO - ##### 05:45:16 INFO - ##### 05:45:16 INFO - ##### Running install step. 05:45:16 INFO - ##### 05:45:16 INFO - Running pre-action listener: _resource_record_pre_action 05:45:16 INFO - Running main action method: install 05:45:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:45:16 INFO - Reading from file tmpfile_stdout 05:45:16 INFO - Detecting whether we're running mozinstall >=1.0... 05:45:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:45:16 INFO - Reading from file tmpfile_stdout 05:45:16 INFO - Output received: 05:45:16 INFO - Usage: mozinstall [options] installer 05:45:16 INFO - Options: 05:45:16 INFO - -h, --help show this help message and exit 05:45:16 INFO - -d DEST, --destination=DEST 05:45:16 INFO - Directory to install application into. [default: 05:45:16 INFO - "/builds/slave/test"] 05:45:16 INFO - --app=APP Application being installed. [default: firefox] 05:45:16 INFO - mkdir: /builds/slave/test/build/application 05:45:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:45:51 INFO - Reading from file tmpfile_stdout 05:45:51 INFO - Output received: 05:45:51 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:45:51 INFO - Running post-action listener: _resource_record_post_action 05:45:51 INFO - ##### 05:45:51 INFO - ##### Finished install step (success) 05:45:51 INFO - ##### 05:45:51 INFO - ##### 05:45:51 INFO - ##### Running run-tests step. 05:45:51 INFO - ##### 05:45:51 INFO - Running pre-action listener: _resource_record_pre_action 05:45:51 INFO - Running main action method: run_tests 05:45:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:45:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:45:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:45:51 INFO - 2016-07-19 05:45:51.324 screenresolution[1637:7612] starting screenresolution argv=screenresolution get 05:45:51 INFO - 2016-07-19 05:45:51.326 screenresolution[1637:7612] Display 0: 1600x1200x32@60 05:45:51 INFO - 2016-07-19 05:45:51.332 screenresolution[1638:7614] starting screenresolution argv=screenresolution list 05:45:51 INFO - Available Modes on Display 0 05:45:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:45:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:45:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:45:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:45:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:45:51 INFO - Intel Iris: 05:45:51 INFO - Chipset Model: Intel Iris 05:45:51 INFO - Type: GPU 05:45:51 INFO - Bus: Built-In 05:45:51 INFO - VRAM (Dynamic, Max): 1536 MB 05:45:51 INFO - Vendor: Intel (0x8086) 05:45:51 INFO - Device ID: 0x0a2e 05:45:51 INFO - Revision ID: 0x0009 05:45:51 INFO - Displays: 05:45:51 INFO - Display: 05:45:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:45:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:45:51 INFO - Display Serial Number: 1600x1200 60 05:45:51 INFO - Main Display: Yes 05:45:51 INFO - Mirror: Off 05:45:51 INFO - Online: Yes 05:45:51 INFO - Rotation: Supported 05:45:51 INFO - Return code: 0 05:45:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:45:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:45:51 INFO - Python 2.7.3 05:45:51 INFO - Return code: 0 05:45:51 INFO - grabbing minidump binary from tooltool 05:45:51 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:51 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 0x101f8fd30>, '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 0x101cf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f87b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:45:51 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 05:45:51 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 05:45:51 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 05:45:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:45:51 INFO - Return code: 0 05:45:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:45:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:45:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:45:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:45:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:45:51 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/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--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 05:45:51 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/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0108 --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 05:45:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PBmqlZHLaN/Render', 05:45:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:51 INFO - 'HOME': '/Users/cltbld', 05:45:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:51 INFO - 'LOGNAME': 'cltbld', 05:45:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:45:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:45:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:51 INFO - 'MOZ_NO_REMOTE': '1', 05:45:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:45:51 INFO - 'NO_EM_RESTART': '1', 05:45:51 INFO - 'PAGER': '/bin/cat', 05:45:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:51 INFO - 'PWD': '/builds/slave/test', 05:45:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:45:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:51 INFO - 'SHELL': '/bin/bash', 05:45:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.05wQR2NITL/Listeners', 05:45:51 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 05:45:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:51 INFO - 'USER': 'cltbld', 05:45:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:51 INFO - 'XPC_FLAGS': '0x0', 05:45:51 INFO - 'XPC_SERVICE_NAME': '0', 05:45:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:51 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/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--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 05:45:51 INFO - mozversion application_buildid: 20160719030224 05:45:51 INFO - mozversion application_changeset: feaaf1af1065257b9178faca8b67eed9657b4a17 05:45:51 INFO - mozversion application_display_name: Nightly 05:45:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:45:51 INFO - mozversion application_name: Firefox 05:45:51 INFO - mozversion application_remotingname: firefox 05:45:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:45:51 INFO - mozversion application_vendor: Mozilla 05:45:51 INFO - mozversion application_version: 50.0a1 05:45:51 INFO - mozversion platform_buildid: 20160719030224 05:45:51 INFO - mozversion platform_changeset: feaaf1af1065257b9178faca8b67eed9657b4a17 05:45:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:45:51 INFO - mozversion platform_version: 50.0a1 05:45:51 INFO - using testdate: 1468932351 05:45:51 INFO - actual date: 1468932351 05:45:51 INFO - starting webserver on 'localhost:49279' 05:45:51 INFO - SUITE-START | Running 2 tests 05:45:51 INFO - TEST-START | dromaeo_css 05:45:51 INFO - operating with platform_type : mac_ 05:45:51 INFO - Initialising browser for dromaeo_css test... 05:45:51 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpLPptWX/profile http://localhost:49279/getInfo.html) 05:45:54 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 05:45:54 INFO - PROCESS | 1648 | colorDepth:24 05:45:54 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 05:45:54 INFO - PROCESS | 1648 | __metrics 05:45:54 INFO - PROCESS | 1648 | 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" 05:45:54 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:45:55 INFO - TEST-INFO | 1648: exit 0 05:45:55 INFO - Browser initialized. 05:45:55 INFO - Running cycle 1/1 for dromaeo_css test... 05:45:55 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpLPptWX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:46:07 INFO - PROCESS | 1651 | RSS: Main: 245071872 05:46:07 INFO - PROCESS | 1651 | 05:46:59 INFO - PROCESS | 1651 | 1468932419130 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] 05:49:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 05:49:10 INFO - PROCESS | 1651 | RSS: Main: 332582912 05:49:10 INFO - PROCESS | 1651 | 05:52:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 05:52:14 INFO - PROCESS | 1651 | RSS: Main: 380579840 05:52:14 INFO - PROCESS | 1651 | 05:55:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 05:55:17 INFO - PROCESS | 1651 | RSS: Main: 373473280 05:55:17 INFO - PROCESS | 1651 | 05:58:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 05:58:20 INFO - PROCESS | 1651 | RSS: Main: 369684480 05:58:20 INFO - PROCESS | 1651 | 06:01:23 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 06:01:24 INFO - PROCESS | 1651 | RSS: Main: 347447296 06:01:24 INFO - PROCESS | 1651 | 06:04:27 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 06:04:27 INFO - PROCESS | 1651 | RSS: Main: 381509632 06:04:27 INFO - PROCESS | 1651 | 06:04:27 INFO - PROCESS | 1651 | __start_tp_report 06:04:27 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 06:04:27 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 06:04:27 INFO - PROCESS | 1651 | |i|pagename|runs| 06:04:27 INFO - PROCESS | 1651 | |0;dojo.html;3359.874442061909;3361.680640339168;3368.7305015598754;3374.8481318340678;3411.897643070709;5578.888422231553;5668.234788303578;5674.886502269952;5717.313922329316;5758.913616295759;5715.571332150084;5733.254676891998;5792.333881603617;5813.854653633662;5847.619904706191;5144.6655967362185;5167.147420675584;5230.34620672416;5241.554467870235;5371.785128594852;5329.573634109262;5359.062164121283;5369.838904832861;5379.838604841861;5419.891602167954;4346.500152482442;5159.948400516003;5212.817551385683;5229.372475302988;5237.3715154181555;5173;5204.42751297357;5260.184671375943;5293.338332708411;5359.249705041298;5173.715445650488;5202.271681964528;5222.712750798704;5224.190250511177;5303.5226829585135;5765.654060756368;5806;5851.707414629294;5854.414558544154;5855.297364316267;6098.522998456991;6144.170536977495;6171.166892469504;6231.40801623845;6258.4680302174365;6267;6317.778877739303;6329.525285603552;6343.238811342622;6351.333110023427;5884.735186916599;5902.3507414184405;5911.172435858984;5919.260092488439;5941.524678025791;6294.716737746812;6304.306526282106;6346.619202847844;6359.96820015897;6373.075903993895;6174.722137503823;6182.103594978703;6184.969075154595;6198.411150940653;6200.81397558074;6268.874622507616;6325;6335.746570137143;6348.936510634927;6357.904631430532;5999.640021598718;6048.727807248684;6058.0610005449225;6127.281156068254;6171.907421388682;6206;6297.968510157511;6322.336154703782;6335.68321583919;6341.080543321193;5007.173816320266;5014.974925125424;5019.974900125476;5028.874278143076;5065.468125846806;4378.890527736832;4440.023194897118;4487.5288094749585;4529.411176547093;4533.229351010336;4757.738324392191;5674.233978412862;5727.255456790674;5731.914021289684;5789.710514474259;6290.937090629127;6296.622202667854;6315.431611155018;6316.968415157895;6337.461315788118;5580.665160090408;5636.97181514098;5651.12407576818;5724.713764311768;5779.624324418899;5450.1007333789485;5476.096444086761;5491.0116179088145;5501.394846566875;5524.281843360418;6327.209098862642;6333.556651034475;6344.619322840645;6352.9047064293145;6355.618662880242;5171.50870667286;6239.032949892774;6243.125962365363;6287.842803929755;6324.873502529832;6270.905936411049;6299.937000629935;6312.936870631235;6338;6350.364963503613;6211.472024877936;6264.655443950626;6265.15420418256;6277;6296.275928268308;6271.749130034747;6300.59046161998;6303.936960630335;6307.558470907176;6316.463100636313;5836.562257830595;5983.192269043623;6114.023656277781;6159.630422174684;6165.0444181152;6197.721102550487;6197.876042479215;6215;6228.283747369111;6228.875422491434;6027.849303767265;6059.515238780798;6075;6084.269887613515;6092.1619379790445;5937.139114828239;6050.818475445743;6130.367562715148;6135.386461354007;6220.471259942956;4660.860174194644;5986.461218490183;6006.699665016819;6011.6693581853415;6044.093385992136 06:04:27 INFO - PROCESS | 1651 | |1;ext.html;468.2461237407775;506.7567567567568;508.6337836757533;540.8566729816597;551.8234165067178;29716.851415742916;30662.693373066264;31613.68386316139;39656.4137434503;41112;42351.78824105875;42585;42631.294748210115;42771.358429623506;42816;9622.037796220391;9796.167325777318;9802.558884850163;9835.065668761456;9841.311108222428;8824.823503529959;8990.505522196276;8994.820103597924;9093.22707569854;9714.22286217101;9052.1853033227;11704.824427633592;11872.347020913847;11906.880931190664;12175.756484870297;11911.523539058428;11969.341686207255;11987.280763154238;11994.760104797942;12003.759924801543;8788.077251888524;11541.653750387502;11887.10846686502;11969.521219151224;11990.880091199064;38899.611003889884;41220.58779412197;41317;41317.173656527004;41436.37845432321;3493.423585108454;3523.94714079289;3526.594441639193;3576.2847430513743;3673.5040769496045;31042.379152417052;32201.03396898097;32683.856065037846;32963.670363296296;33052.008439746845;3319.68462996015;3337.1156643489494;3359.9160020999425;3376.6792154745503;3466.861325546975;664765;674949;675133.6243318801;675824;677234;60292.698536507036;60326.396736032955;60962;61117.77764444686;61128.38871611227;60078;60100.69949650311;60118;60122;60218.69890650519;16348.509544713677;16564.585885352964;16589.751153732705;16890.40883569069;16913;12976.610701678965;13064.738705225842;13081.607551773463;13104.737905241842;13107.951363890867;15253.923730381277;15331.156786376856;16578.336866525446;16849.747253791204;18551.832920693363;7697.191794861571;7749.806254843674;7807.414443916729;7823;7877.133515313311;903.9186473217442;1012.5747186181821;1050.3172937590475;1079.8866119057543;1081.5133190064503;30447.934322298614;30557.08328750141;30674.386512270077;31230.84384578108;31234.843825780725;1053.4206186597341;1085.5440714899762;1089.727568107973;1124.145649306533;1179.7640471905654;15864.603384915239;15891.920540397225;15907.284172212278;15936.601584960237;16377.836221637632;605408;725966;727457;730683;731016;59169.70415147897;59451.70274148688;59574.702126489094;59614.40385596088;59641.105383419286;1355.9254241016642;1393.2754967416886;1414.929253537319;1418.865207805257;1419.84381718013;30146.944856929902;30200.375954885556;30221.546676800306;30750.538741919336;31008.0697579073;49813.750931245115;49914.750426247636;50173.498265018345;50281.49718502768;50283.49716502788;608780;610643;610752;612212;615187.9240603768;22417.77582224157;26623.467530649665;26632.866835665696;26788.464230714886;26902.192934212006;32661;40552.59447405606;48100.54894557711;48598;49267.50732492773;38869.41695874621;40572.59427405688;40794;40964.59035409608;41818.1636367277;38832.223355532165;39855.40216896807;40153.39769903512;40170.79914600525;40869.1391602585;36989.44515832211;37137.81431092935;37139.442908356934;38982.6101738979;39464.01339966524;30714.692853071185;33752.66247337593;34083.14792130217;34751.13122171865;35244.29511409809;22402.66396004094;25094.74905250924;25839.483210336064;26296;26489.867550662122 06:04:27 INFO - PROCESS | 1651 | |2;jquery.html;380.4673457159976;388.80365415465184;389.78367006311487;397.9920401591966;407.91229885574614;19478;21412.678809817866;22309;22314.21900233492;22514.324570262917;18232.544186395346;21496.677549836764;22042.88978555109;22225.777742222574;22298;5605.803796867101;6131.294901086365;6144.5698801083945;6145.262568491765;6159.260888693363;4221.957780422203;4477.910441791162;6049.243844519435;6483.902741458882;6801.7959461216;6511.088447617338;6796.388325050744;6805.863882722342;6816.693248803816;6819.045333653267;6756.189257289107;6769.559978601375;6794.932050679479;6798.150231221098;6802.625855577941;5806.042003069489;5836.533077353802;5887.4406931341455;5889.087191485327;5893.263342082239;27262.318442038908;27362.452750945067;27592;27751.861240693666;27802.3049423764;2609.5694210455254;2629.6581444412295;2695.528282550546;2944.2399833344916;3257.071734555664;7170.426365890717;7182.784516464514;15931.203439828078;15967.844889491751;16057.91971040149;1868.7944326124252;1888.2352647177916;1889.9321883135983;1952.2288695965015;1961.969965767969;103189;107413.46293268484;107587.84824303548;118099;121580;9477.573509192103;11788.29270243788;11844.526218951318;11856.17006809515;11982.783185110608;14659.853401466062;14807.111573305636;14836.851631483547;14840.332185051482;14857.777133343008;8602.569871506525;8812.07473215316;8834.911650883409;8877.911220887709;8913.420627659181;8736.344774141964;8756.25571826402;8757.386982911135;8861.202491775643;8882.422642528216;4513.526079761644;4602.470715867651;4657.930131048037;4659.906801863944;4660.627149828006;1467.9706405871823;1805.161940842769;1833.8624603154549;1901.4200668795872;1936.3029309448586;861.9870701939476;882.960266787996;889.6041261638638;1045.749020235138;1063.441693111123;19924.30264940749;20586.58826823485;20934.267300644406;21015.8949205253;21133.68299475509;954.8519979403204;968.9612415503442;1001.4692213127055;1003.2726273451835;1009.8232809258498;10861.055287658459;11167.553297868157;11255.402613217791;11353.772924541463;11407.657770266906;538416;583863;591532;595266;598979;125199;132911;135462;135572;138475;962.43216502264;975.2588033094758;987.2595553334999;1009.8283291840552;1011.8431643095332;15994.920025399799;17756.91121544384;18907.905460472608;19108.713369299192;19171.61656766829;46905.29642055304;52597;52864.67838304073;54511.454885450636;54660;44071.55928440803;44804.535750534036;44848.77575612101;72830.90753638593;73085.6345718268;7995.720149794729;8363.414560980917;8455.28130108923;8464.44981076228;8682.218600325748;9856.950715246378;13328.200307981551;13472.32638368058;13634.250116243702;13642.590722278348;11455.828162577734;11535.8269625954;11576.01603863681;11648.068154547447;11664.88335116638;11328.603498877499;11342.546298147981;11348.943255283672;11545.653630391102;11559.537618495502;11669.649910502698;11692.35692036912;11801.114916381133;11838.704032398915;11915.463804128662;8331.750047498585;8348.081711011906;8386;8427.62075706607;8455.196756308142;9426.905730942603;10871.34771913656;11319.037881780141;11460.02589779878;11534.423278835857 06:04:27 INFO - PROCESS | 1651 | |3;mootools.html;1561.9843801561985;1606.606381436548;1774.6983012887808;1894.526368407898;1900.686386746187;7907.604619769003;8174.427790054699;8563.700270490534;8999.5050272235;9013.684521041765;8436.15638436156;8674.178422531277;8682.86975695365;8804.20762131408;8827.514486703229;2286.8627882327073;2367.5738367094004;2368.0409434179187;2370.4073981504625;2372.7389987101405;2075.30477290108;2141.314779270634;2283.5547068321684;2318.3045086474076;2351.482673811767;1915.7030660247615;2367.8342516023886;2369.30105618842;2371.727251366089;2371.8102551795814;2319.188284100568;2342.367560758594;2363.078399424226;2363.1728894886824;2363.6690863279155;1934.4293433437028;2271.7728227177317;2330.685357476736;2335.0426325206586;2348.553774782803;8946.821063578756;9010.819783604291;9024.458532488072;9034.051424600453;9070.86393704095;5165.819196328148;5316.3354580677415;5317.229001794757;5323.733813309319;5326.121190003645;3468.6531346865363;3549.698275646573;3610.7291953103622;3619.8914032579064;3624.7643903146213;1048.1405247696891;1076.1874784537624;1088.8584484017026;1098.961536346224;1099.6700989703056;11443.370614616193;11540.422978851024;11556.595519156874;11569.942150289195;11583.826242606368;4381.16757816014;4444.755538445352;4542.545745425431;4560.110778398181;4583.358329833826;8375.45559538628;8745.038045814956;9116.1339672731;9236.214921731593;9243.584038718274;5177.4822517747925;5182.144946083929;5182.533571978464;5187.066328060985;5191.844244672666;4666.32338310943;4834.444038935497;4841.12859685253;4850.417949846041;4855.077535268288;3708.5735140458655;3739.5886452490204;3759.812009399519;3794.734368594227;3827.5981021992848;1078.0890147825075;1088.450332582041;1121.2599684208383;1130.8190689489807;1137.4028634966708;773.9651715672808;788.5505262000604;922.4280945813505;935.4153653966271;984.7685793838442;6014.8195554133445;6026.276846778414;6176.907346389808;6241.556201424779;6287.402696743802;954.6229239450378;968.801395713877;974.0892265731563;986.4820968991194;1010.7018429563296;3921.117748506563;4056.2090392373793;4112.157007813391;4159.1265834175165;4163.625273725379;10296.794064118676;10727.892721072845;10732.141428685687;10917.617883374043;10935.507902144423;9849.655262065793;10680.626178083885;10775.245732798629;10799.514021869034;10807.67576972692;8011.399145064143;8289.04675962272;8293.792655183668;8430.915690843012;8459.577021149042;5998.49012833914;6010.278766548042;6109.877802443837;6193.81418557444;6335.683215839282;9019.549022548978;9835.950820245853;10140.03669651382;10161.593536258466;10199.898001019896;10251.436171010666;10365.844512332473;10397.11624511925;10404.323718958243;10409;7494.951006058663;7524.097108347033;7573.242675732383;7669;7672.73145439907;8261.793455163668;8317.459365141247;8339.666413343399;8347.457415267989;8399.916000839914;7425.777226683208;7548.320651141424;7606.201348858512;7669.884951725617;7761.107472640719;7312.670929808278;7414.406847452299;7422.443316751157;7514.774556763306;7590.0891892973195;7025.192102908231;7052.5063245574365;7085.574865508086;7094.53885497441;7104.680289386886;4921.753912304299;5123.096769052185;5149.382074151126;5150.0472412603785;5240.292560504436;5013.348264725562;5082.339295891658;5094.719790411563;5159.587233021424;5299.973500132474 06:04:27 INFO - PROCESS | 1651 | |4;prototype.html;305.5844052089158;318.73226489748606;319.6116718187403;324.37881457009826;327.28814827749636;4454.665900057496;4689.976550117249;5399.460053994599;5421.810236641718;5451.282845903537;4765.927666275086;5338.62629615927;5433.179884738617;5457.645253058558;5469.945300546993;3914.2954268231706;3936.5276166860017;4070;4070.107400058972;4181.874543763677;3357.7145942594907;4044.9191016179657;4074.327735923569;4087.6729861611;4140.192662430827;3629.691476224524;3959.366501359783;3962.4056391541353;4000.8799736007823;4092.5088989321325;3392.728581713457;3677.9771125880657;3873.6513713765758;4027.2549578328103;4133.6693064554765;3545.4504551794257;3568.839402226906;3579.8031108289033;3584.462330650423;3591.066322756102;5248.0553500369915;5280.260763493114;5325.387580428262;5329.920051199235;5348.224507446437;4477.507474177838;4658.58072773452;4734.668573199878;4741.596964258042;4745.335653008581;5297.655652382621;5609.9902017636805;5612.971935140339;5616.382197958222;5659.490645841894;3641.526601541809;3965.722399432041;3976.1848820991627;3986.681065514752;4001.339778936472;4961.485048605751;5584.263710781728;5697.316322041382;5887.558433117448;5928.614640048383;3674.889753307405;3957.722959392815;3979.3235150024566;3990.2418540477215;4017.819198136091;3735.831887565066;4071.2875246832277;4079.551249362568;4080.4287399764057;4105.651019663303;4113.572991934773;4505.166544189336;4705.835295764632;4712.670113092051;4725.669203155746;4715.693479923794;5622.184783206412;5724.4561766632105;5736.368999410061;5807.593468457168;1203.8976686981705;1259.8677138900466;1399.9090059146122;1414.5826981040618;1421.3461807568635;731.1847290271415;760.5474742528187;813.6094674556247;850.1795767084793;866.9393142479965;4987.426445958688;5120.590352771769;5182.708979797855;5292.576593872481;5310.176922577007;5344.171653393731;5617.606767526316;5811.3316968548315;5813;5825.252424272701;4947.406311242674;5243.318368612056;5307.920381194285;5320.935812837448;5387.622866399314;2865.312325041975;3038.255627371308;3045.193023848682;3056.205386599456;3063.2495038715656;6037.78867739627;6050.939490605126;6083.665398403042;6120.460009096175;6189.597676151036;4369.80335884886;5795.928158076226;5947.186108236942;5970.850728731816;6103.8474038148515;5741.598088133874;5958.314793798769;6017.3681763414215;6040.184575082308;6043.486303664238;5219.451957544555;5436.7281635918835;5832.679202643895;5896.582401215481;5907.2911250650195;4619.93070103942;4951.830828269982;5205.375354957429;5667.319921609434;5704.543636508986;5133.281340612392;5312.229726689686;5971.671558064349;6010.368911264254;6088.4824789893355;3786.6368107481835;3893.8831835044994;3963.4054891766464;3966.2662407454714;3993.181397813499;4738.33663287147;4802.655256528178;5718.742656580547;5773.04744717117;5806.187133801187;5601.60788744784;5684.602077854511;5691.23168372281;5696.430356964271;5753.884922301614;4742.810287588596;5422.620416570802;5502.9174562380795;5635.492805647511;5713.286189060163;4082.1222237770958;4211.536730959653;5053.797848086182;5424.810131645373;5437.646552974044;4700;4787.904241915073;5291.76187071575;5462.743568979077;5562.471565201299;4818.494058123987;5467;5493;5494.752736126964;5508.586855985737 06:04:27 INFO - PROCESS | 1651 | |5;yui.html;725.3725527418783;770.6069904348782;784.7528028670969;796.4703472190994;837.6942416018152;14610.926945365272;14956.925215373907;15016.39934402624;15025.02337348073;15070;14754.188519631416;14953.47662831801;14958.252087395642;14976.475823346185;15058.54824355271;691.4019373242135;720.7225218290944;720.805382546712;725.2529894208973;729.2925861913935;711.2993701204309;856.3919617071855;894.7718331825375;895.6059333893066;899.2401420799414;927.5686805635412;940.7789169545151;944.6693657219987;944.9291303152256;944.9905500944972;937.9906200937971;939.6617217801586;939.9060093990546;940.6707652321701;943.0852073488705;544;569.8347479231018;569.8774763425884;574.5231457889942;705.4427002667937;14729.852701472955;15347.769783453257;15350.769738453931;15362.923185384114;15393.153376564285;14648.560543183721;15063.096214227182;15084.472043478536;15197.6200594986;15207.847921520754;11943.462544185533;12170.452329645188;12395.194312369757;12408.937955310255;12499.56251531201;3840.135969406889;3888.786116763577;3974.2448934702315;4085.3259212229946;4114.5679703630985;108196.91803081948;109096;109377;109523;113773.99999999917;11637.418129093512;11649.825252621218;11717.765644687059;11741.941290293666;12260.570880019157;24121.879390603284;24124.87937560301;24158.396040098785;24173.39566510816;24188.758112419004;7954.125046244909;8378.036525799489;8520.659173633108;8535.146485351415;8606.913930860612;4129.731567448166;4588.908221835544;4677.134730074947;4681.368015317957;4706.081636516717;3455.1534873956043;3531.788092714445;3553.1117220694828;3556.893293201156;3559.2703495783303;639.0510092512615;645.7610684046932;647.3914520350855;659.1036190780535;663.9568428052161;606.902895536712;659.152988409891;692.8152858611729;709.7480394459996;715.5670819154441;8367.534761087505;9302.767430814283;9390.62437502506;9440.811183776286;9596.424214547149;575.9078547432391;618.7215752911208;650.7331993882485;668.7024274198033;670.1790306874041;4192.790360481964;4341.261985462479;4559.338895860148;4626.4910859805395;4672.76636168197;143640;152283;153888;155045.2247738754;157390;122769;126222.36888815498;126454.41273523618;127141;130417.34791325983;113768.43115784368;116690.41654791671;119257;119462.40268798775;119540.8045919547;8898.42160259581;9069.77325566866;9488.098630630078;9548.618055277711;9655.034496550288;60897;61377.69311153416;61752.38247617646;63042.369576305486;63499.682501587195;88279.55860220658;93967;94103;94660.05339946512;96795.51602241944;11185.38480383554;13224.669383265495;13354.732905342033;13510.392032358372;13904.165750055028;15335.923320383326;15390.230488475756;15702.450414235904;15830.762538562161;15937.12345820991;13971;14029.579112626638;14430.639234018814;14470.348834302693;14841.480548180762;13954.860451395356;14263.286835657967;14539.709205816036;14567.198804065878;14808.25958702082;13598.456061757815;13677.658058548615;14065.507707230197;14336.56990290293;14503.564893053226;11248.987591116838;11261.436928153396;11376.488058037574;11854.466549005143;11972.820407693718;11056.568373925818;11601.651950441501;11691.824622630496;11838.526458941546;11906.166568340133 06:04:27 INFO - PROCESS | 1651 | __end_tp_report 06:04:27 INFO - PROCESS | 1651 | __start_cc_report 06:04:27 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1275 06:04:27 INFO - PROCESS | 1651 | __end_cc_report 06:04:27 INFO - PROCESS | 1651 | __startTimestamp1468933467770__endTimestamp 06:04:27 INFO - PROCESS | 1651 | 06:04:27 INFO - PROCESS | 1651 | ------- Summary: start ------- 06:04:27 INFO - PROCESS | 1651 | Number of tests: 6 06:04:27 INFO - PROCESS | 1651 | 06:04:27 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5769.33 Median:6006.70 stddev:657.20 (10.9%) stddev-sans-first:631.45 06:04:27 INFO - PROCESS | 1651 | Values: 3359.9 3361.7 3368.7 3374.8 3411.9 5578.9 5668.2 5674.9 5717.3 5758.9 5715.6 5733.3 5792.3 5813.9 5847.6 5144.7 5167.1 5230.3 5241.6 5371.8 5329.6 5359.1 5369.8 5379.8 5419.9 4346.5 5159.9 5212.8 5229.4 5237.4 5173.0 5204.4 5260.2 5293.3 5359.2 5173.7 5202.3 5222.7 5224.2 5303.5 5765.7 5806.0 5851.7 5854.4 5855.3 6098.5 6144.2 6171.2 6231.4 6258.5 6267.0 6317.8 6329.5 6343.2 6351.3 5884.7 5902.4 5911.2 5919.3 5941.5 6294.7 6304.3 6346.6 6360.0 6373.1 6174.7 6182.1 6185.0 6198.4 6200.8 6268.9 6325.0 6335.7 6348.9 6357.9 5999.6 6048.7 6058.1 6127.3 6171.9 6206.0 6298.0 6322.3 6335.7 6341.1 5007.2 5015.0 5020.0 5028.9 5065.5 4378.9 4440.0 4487.5 4529.4 4533.2 4757.7 5674.2 5727.3 5731.9 5789.7 6290.9 6296.6 6315.4 6317.0 6337.5 5580.7 5637.0 5651.1 5724.7 5779.6 5450.1 5476.1 5491.0 5501.4 5524.3 6327.2 6333.6 6344.6 6352.9 6355.6 5171.5 6239.0 6243.1 6287.8 6324.9 6270.9 6299.9 6312.9 6338.0 6350.4 6211.5 6264.7 6265.2 6277.0 6296.3 6271.7 6300.6 6303.9 6307.6 6316.5 5836.6 5983.2 6114.0 6159.6 6165.0 6197.7 6197.9 6215.0 6228.3 6228.9 6027.8 6059.5 6075.0 6084.3 6092.2 5937.1 6050.8 6130.4 6135.4 6220.5 4660.9 5986.5 6006.7 6011.7 6044.1 06:04:27 INFO - PROCESS | 1651 | 06:04:27 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:78139.15 Median:20331.47 stddev:178277.14 (876.9%) stddev-sans-first:178681.87 06:04:27 INFO - PROCESS | 1651 | Values: 468.2 506.8 508.6 540.9 551.8 29716.9 30662.7 31613.7 39656.4 41112.0 42351.8 42585.0 42631.3 42771.4 42816.0 9622.0 9796.2 9802.6 9835.1 9841.3 8824.8 8990.5 8994.8 9093.2 9714.2 9052.2 11704.8 11872.3 11906.9 12175.8 11911.5 11969.3 11987.3 11994.8 12003.8 8788.1 11541.7 11887.1 11969.5 11990.9 38899.6 41220.6 41317.0 41317.2 41436.4 3493.4 3523.9 3526.6 3576.3 3673.5 31042.4 32201.0 32683.9 32963.7 33052.0 3319.7 3337.1 3359.9 3376.7 3466.9 664765.0 674949.0 675133.6 675824.0 677234.0 60292.7 60326.4 60962.0 61117.8 61128.4 60078.0 60100.7 60118.0 60122.0 60218.7 16348.5 16564.6 16589.8 16890.4 16913.0 12976.6 13064.7 13081.6 13104.7 13108.0 15253.9 15331.2 16578.3 16849.7 18551.8 7697.2 7749.8 7807.4 7823.0 7877.1 903.9 1012.6 1050.3 1079.9 1081.5 30447.9 30557.1 30674.4 31230.8 31234.8 1053.4 1085.5 1089.7 1124.1 1179.8 15864.6 15891.9 15907.3 15936.6 16377.8 605408.0 725966.0 727457.0 730683.0 731016.0 59169.7 59451.7 59574.7 59614.4 59641.1 1355.9 1393.3 1414.9 1418.9 1419.8 30146.9 30200.4 30221.5 30750.5 31008.1 49813.8 49914.8 50173.5 50281.5 50283.5 608780.0 610643.0 610752.0 612212.0 615187.9 22417.8 26623.5 26632.9 26788.5 26902.2 32661.0 40552.6 48100.5 48598.0 49267.5 38869.4 40572.6 40794.0 40964.6 41818.2 38832.2 39855.4 40153.4 40170.8 40869.1 36989.4 37137.8 37139.4 38982.6 39464.0 30714.7 33752.7 34083.1 34751.1 35244.3 22402.7 25094.7 25839.5 26296.0 26489.9 06:04:27 INFO - PROCESS | 1651 | 06:04:27 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:34236.71 Median:27312.39 stddev:97064.45 (355.4%) stddev-sans-first:97303.45 06:04:27 INFO - PROCESS | 1651 | Values: 380.5 388.8 389.8 398.0 407.9 19478.0 21412.7 22309.0 22314.2 22514.3 18232.5 21496.7 22042.9 22225.8 22298.0 5605.8 6131.3 6144.6 6145.3 6159.3 4222.0 4477.9 6049.2 6483.9 6801.8 6511.1 6796.4 6805.9 6816.7 6819.0 6756.2 6769.6 6794.9 6798.2 6802.6 5806.0 5836.5 5887.4 5889.1 5893.3 27262.3 27362.5 27592.0 27751.9 27802.3 2609.6 2629.7 2695.5 2944.2 3257.1 7170.4 7182.8 15931.2 15967.8 16057.9 1868.8 1888.2 1889.9 1952.2 1962.0 103189.0 107413.5 107587.8 118099.0 121580.0 9477.6 11788.3 11844.5 11856.2 11982.8 14659.9 14807.1 14836.9 14840.3 14857.8 8602.6 8812.1 8834.9 8877.9 8913.4 8736.3 8756.3 8757.4 8861.2 8882.4 4513.5 4602.5 4657.9 4659.9 4660.6 1468.0 1805.2 1833.9 1901.4 1936.3 862.0 883.0 889.6 1045.7 1063.4 19924.3 20586.6 20934.3 21015.9 21133.7 954.9 969.0 1001.5 1003.3 1009.8 10861.1 11167.6 11255.4 11353.8 11407.7 538416.0 583863.0 591532.0 595266.0 598979.0 125199.0 132911.0 135462.0 135572.0 138475.0 962.4 975.3 987.3 1009.8 1011.8 15994.9 17756.9 18907.9 19108.7 19171.6 46905.3 52597.0 52864.7 54511.5 54660.0 44071.6 44804.5 44848.8 72830.9 73085.6 7995.7 8363.4 8455.3 8464.4 8682.2 9857.0 13328.2 13472.3 13634.3 13642.6 11455.8 11535.8 11576.0 11648.1 11664.9 11328.6 11342.5 11348.9 11545.7 11559.5 11669.6 11692.4 11801.1 11838.7 11915.5 8331.8 8348.1 8386.0 8427.6 8455.2 9426.9 10871.3 11319.0 11460.0 11534.4 06:04:27 INFO - PROCESS | 1651 | 06:04:27 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5711.04 Median:5088.53 stddev:3187.37 (62.6%) stddev-sans-first:3181.06 06:04:27 INFO - PROCESS | 1651 | Values: 1562.0 1606.6 1774.7 1894.5 1900.7 7907.6 8174.4 8563.7 8999.5 9013.7 8436.2 8674.2 8682.9 8804.2 8827.5 2286.9 2367.6 2368.0 2370.4 2372.7 2075.3 2141.3 2283.6 2318.3 2351.5 1915.7 2367.8 2369.3 2371.7 2371.8 2319.2 2342.4 2363.1 2363.2 2363.7 1934.4 2271.8 2330.7 2335.0 2348.6 8946.8 9010.8 9024.5 9034.1 9070.9 5165.8 5316.3 5317.2 5323.7 5326.1 3468.7 3549.7 3610.7 3619.9 3624.8 1048.1 1076.2 1088.9 1099.0 1099.7 11443.4 11540.4 11556.6 11569.9 11583.8 4381.2 4444.8 4542.5 4560.1 4583.4 8375.5 8745.0 9116.1 9236.2 9243.6 5177.5 5182.1 5182.5 5187.1 5191.8 4666.3 4834.4 4841.1 4850.4 4855.1 3708.6 3739.6 3759.8 3794.7 3827.6 1078.1 1088.5 1121.3 1130.8 1137.4 774.0 788.6 922.4 935.4 984.8 6014.8 6026.3 6176.9 6241.6 6287.4 954.6 968.8 974.1 986.5 1010.7 3921.1 4056.2 4112.2 4159.1 4163.6 10296.8 10727.9 10732.1 10917.6 10935.5 9849.7 10680.6 10775.2 10799.5 10807.7 8011.4 8289.0 8293.8 8430.9 8459.6 5998.5 6010.3 6109.9 6193.8 6335.7 9019.5 9836.0 10140.0 10161.6 10199.9 10251.4 10365.8 10397.1 10404.3 10409.0 7495.0 7524.1 7573.2 7669.0 7672.7 8261.8 8317.5 8339.7 8347.5 8399.9 7425.8 7548.3 7606.2 7669.9 7761.1 7312.7 7414.4 7422.4 7514.8 7590.1 7025.2 7052.5 7085.6 7094.5 7104.7 4921.8 5123.1 5149.4 5150.0 5240.3 5013.3 5082.3 5094.7 5159.6 5300.0 06:04:27 INFO - PROCESS | 1651 | 06:04:27 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4565.38 Median:5158.00 stddev:1401.49 (27.2%) stddev-sans-first:1368.46 06:04:27 INFO - PROCESS | 1651 | Values: 305.6 318.7 319.6 324.4 327.3 4454.7 4690.0 5399.5 5421.8 5451.3 4765.9 5338.6 5433.2 5457.6 5469.9 3914.3 3936.5 4070.0 4070.1 4181.9 3357.7 4044.9 4074.3 4087.7 4140.2 3629.7 3959.4 3962.4 4000.9 4092.5 3392.7 3678.0 3873.7 4027.3 4133.7 3545.5 3568.8 3579.8 3584.5 3591.1 5248.1 5280.3 5325.4 5329.9 5348.2 4477.5 4658.6 4734.7 4741.6 4745.3 5297.7 5610.0 5613.0 5616.4 5659.5 3641.5 3965.7 3976.2 3986.7 4001.3 4961.5 5584.3 5697.3 5887.6 5928.6 3674.9 3957.7 3979.3 3990.2 4017.8 3735.8 4071.3 4079.6 4080.4 4105.7 4113.6 4505.2 4705.8 4712.7 4725.7 4715.7 5622.2 5724.5 5736.4 5807.6 1203.9 1259.9 1399.9 1414.6 1421.3 731.2 760.5 813.6 850.2 866.9 4987.4 5120.6 5182.7 5292.6 5310.2 5344.2 5617.6 5811.3 5813.0 5825.3 4947.4 5243.3 5307.9 5320.9 5387.6 2865.3 3038.3 3045.2 3056.2 3063.2 6037.8 6050.9 6083.7 6120.5 6189.6 4369.8 5795.9 5947.2 5970.9 6103.8 5741.6 5958.3 6017.4 6040.2 6043.5 5219.5 5436.7 5832.7 5896.6 5907.3 4619.9 4951.8 5205.4 5667.3 5704.5 5133.3 5312.2 5971.7 6010.4 6088.5 3786.6 3893.9 3963.4 3966.3 3993.2 4738.3 4802.7 5718.7 5773.0 5806.2 5601.6 5684.6 5691.2 5696.4 5753.9 4742.8 5422.6 5502.9 5635.5 5713.3 4082.1 4211.5 5053.8 5424.8 5437.6 4700.0 4787.9 5291.8 5462.7 5562.5 4818.5 5467.0 5493.0 5494.8 5508.6 06:04:27 INFO - PROCESS | 1651 | 06:04:27 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:25438.62 Median:3493.47 stddev:40244.77 (1152.0%) stddev-sans-first:40314.89 06:04:27 INFO - PROCESS | 1651 | Values: 725.4 770.6 784.8 796.5 837.7 14610.9 14956.9 15016.4 15025.0 15070.0 14754.2 14953.5 14958.3 14976.5 15058.5 691.4 720.7 720.8 725.3 729.3 711.3 856.4 894.8 895.6 899.2 927.6 940.8 944.7 944.9 945.0 938.0 939.7 939.9 940.7 943.1 544.0 569.8 569.9 574.5 705.4 14729.9 15347.8 15350.8 15362.9 15393.2 14648.6 15063.1 15084.5 15197.6 15207.8 11943.5 12170.5 12395.2 12408.9 12499.6 3840.1 3888.8 3974.2 4085.3 4114.6 108196.9 109096.0 109377.0 109523.0 113774.0 11637.4 11649.8 11717.8 11741.9 12260.6 24121.9 24124.9 24158.4 24173.4 24188.8 7954.1 8378.0 8520.7 8535.1 8606.9 4129.7 4588.9 4677.1 4681.4 4706.1 3455.2 3531.8 3553.1 3556.9 3559.3 639.1 645.8 647.4 659.1 664.0 606.9 659.2 692.8 709.7 715.6 8367.5 9302.8 9390.6 9440.8 9596.4 575.9 618.7 650.7 668.7 670.2 4192.8 4341.3 4559.3 4626.5 4672.8 143640.0 152283.0 153888.0 155045.2 157390.0 122769.0 126222.4 126454.4 127141.0 130417.3 113768.4 116690.4 119257.0 119462.4 119540.8 8898.4 9069.8 9488.1 9548.6 9655.0 60897.0 61377.7 61752.4 63042.4 63499.7 88279.6 93967.0 94103.0 94660.1 96795.5 11185.4 13224.7 13354.7 13510.4 13904.2 15335.9 15390.2 15702.5 15830.8 15937.1 13971.0 14029.6 14430.6 14470.3 14841.5 13954.9 14263.3 14539.7 14567.2 14808.3 13598.5 13677.7 14065.5 14336.6 14503.6 11249.0 11261.4 11376.5 11854.5 11972.8 11056.6 11601.7 11691.8 11838.5 11906.2 06:04:27 INFO - PROCESS | 1651 | -------- Summary: end -------- 06:04:27 INFO - PROCESS | 1651 | 06:04:28 INFO - TEST-INFO | 1651: exit 0 06:04:28 INFO - TEST-OK | dromaeo_css | took 1116488ms 06:04:28 INFO - TEST-START | kraken 06:04:28 INFO - operating with platform_type : mac_ 06:04:28 INFO - Initialising browser for kraken test... 06:04:28 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpgVq5gy/profile http://localhost:49279/getInfo.html) 06:04:30 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 06:04:30 INFO - PROCESS | 1660 | colorDepth:24 06:04:30 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 06:04:30 INFO - PROCESS | 1660 | __metrics 06:04:30 INFO - PROCESS | 1660 | 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" 06:04:30 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:04:31 INFO - TEST-INFO | 1660: exit 0 06:04:31 INFO - Browser initialized. 06:04:31 INFO - Running cycle 1/1 for kraken test... 06:04:31 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpgVq5gy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:04:43 INFO - PROCESS | 1662 | RSS: Main: 245125120 06:04:43 INFO - PROCESS | 1662 | 06:04:46 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 06:04:47 INFO - PROCESS | 1662 | RSS: Main: 294662144 06:04:47 INFO - PROCESS | 1662 | 06:04:49 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 06:04:50 INFO - PROCESS | 1662 | RSS: Main: 305299456 06:04:50 INFO - PROCESS | 1662 | 06:04:53 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 06:04:54 INFO - PROCESS | 1662 | RSS: Main: 391462912 06:04:54 INFO - PROCESS | 1662 | 06:04:56 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 06:04:57 INFO - PROCESS | 1662 | RSS: Main: 292384768 06:04:57 INFO - PROCESS | 1662 | 06:04:59 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:04:59 INFO - PROCESS | 1662 | RSS: Main: 296439808 06:04:59 INFO - PROCESS | 1662 | 06:05:02 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 06:05:03 INFO - PROCESS | 1662 | RSS: Main: 333668352 06:05:03 INFO - PROCESS | 1662 | 06:05:06 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 06:05:06 INFO - PROCESS | 1662 | RSS: Main: 334364672 06:05:06 INFO - PROCESS | 1662 | 06:05:09 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 06:05:10 INFO - PROCESS | 1662 | RSS: Main: 334540800 06:05:10 INFO - PROCESS | 1662 | 06:05:12 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:05:12 INFO - PROCESS | 1662 | RSS: Main: 312442880 06:05:12 INFO - PROCESS | 1662 | 06:05:14 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:05:15 INFO - PROCESS | 1662 | RSS: Main: 318521344 06:05:15 INFO - PROCESS | 1662 | 06:05:17 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:05:18 INFO - PROCESS | 1662 | RSS: Main: 316272640 06:05:18 INFO - PROCESS | 1662 | 06:05:21 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:05:21 INFO - PROCESS | 1662 | RSS: Main: 326524928 06:05:21 INFO - PROCESS | 1662 | 06:05:24 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:05:25 INFO - PROCESS | 1662 | RSS: Main: 329404416 06:05:25 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 06:05:27 INFO - PROCESS | 1662 | RSS: Main: 372363264 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | __start_tp_report 06:05:27 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 06:05:27 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 06:05:27 INFO - PROCESS | 1662 | |i|pagename|runs| 06:05:27 INFO - PROCESS | 1662 | |0;ai-astar;103;113;114;112;114;113;113;113;114;113 06:05:27 INFO - PROCESS | 1662 | |1;audio-beat-detection;141;109;107;156;114;138;138;127;145;107 06:05:27 INFO - PROCESS | 1662 | |2;audio-dft;180;183;182;182;188;184;182;180;180;184 06:05:27 INFO - PROCESS | 1662 | |3;audio-fft;82;95;84;114;58;56;66;60;83;57 06:05:27 INFO - PROCESS | 1662 | |4;audio-oscillator;83;92;94;71;70;67;96;69;68;97 06:05:27 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;87;85;88;84;87;93;135;84;87;84 06:05:27 INFO - PROCESS | 1662 | |6;imaging-darkroom;93;92;92;94;93;93;93;93;93;92 06:05:27 INFO - PROCESS | 1662 | |7;imaging-desaturate;85;86;99;132;120;84;99;131;83;86 06:05:27 INFO - PROCESS | 1662 | |8;json-parse-financial;55;51;51;53;73;84;83;82;52;53 06:05:27 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;66;69;75;72;73;72;72;70;73;72 06:05:27 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;86;86;86;83;84;92;101;102;83;79 06:05:27 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;137;137;137;137;134;137;136;134;232;151 06:05:27 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;167;164;167;166;166;169;165;166;169;234 06:05:27 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;69;65;60;71;79;87;87;88;67;67 06:05:27 INFO - PROCESS | 1662 | __end_tp_report 06:05:27 INFO - PROCESS | 1662 | __start_cc_report 06:05:27 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2065 06:05:27 INFO - PROCESS | 1662 | __end_cc_report 06:05:27 INFO - PROCESS | 1662 | __startTimestamp1468933527528__endTimestamp 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | ------- Summary: start ------- 06:05:27 INFO - PROCESS | 1662 | Number of tests: 14 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.00 stddev:3.29 (2.9%) stddev-sans-first:0.67 06:05:27 INFO - PROCESS | 1662 | Values: 103.0 113.0 114.0 112.0 114.0 113.0 113.0 113.0 114.0 113.0 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:138.00 stddev:17.89 (13.0%) stddev-sans-first:18.37 06:05:27 INFO - PROCESS | 1662 | Values: 141.0 109.0 107.0 156.0 114.0 138.0 138.0 127.0 145.0 107.0 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:182.50 Median:182.50 stddev:2.46 (1.3%) stddev-sans-first:2.44 06:05:27 INFO - PROCESS | 1662 | Values: 180.0 183.0 182.0 182.0 188.0 184.0 182.0 180.0 180.0 184.0 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:75.50 Median:74.00 stddev:19.42 (26.2%) stddev-sans-first:20.45 06:05:27 INFO - PROCESS | 1662 | Values: 82.0 95.0 84.0 114.0 58.0 56.0 66.0 60.0 83.0 57.0 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:80.70 Median:87.50 stddev:12.93 (14.8%) stddev-sans-first:13.68 06:05:27 INFO - PROCESS | 1662 | Values: 83.0 92.0 94.0 71.0 70.0 67.0 96.0 69.0 68.0 97.0 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:91.40 Median:87.00 stddev:15.56 (17.9%) stddev-sans-first:16.42 06:05:27 INFO - PROCESS | 1662 | Values: 87.0 85.0 88.0 84.0 87.0 93.0 135.0 84.0 87.0 84.0 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 06:05:27 INFO - PROCESS | 1662 | Values: 93.0 92.0 92.0 94.0 93.0 93.0 93.0 93.0 93.0 92.0 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:100.50 Median:85.50 stddev:19.85 (23.2%) stddev-sans-first:20.25 06:05:27 INFO - PROCESS | 1662 | Values: 85.0 86.0 99.0 132.0 120.0 84.0 99.0 131.0 83.0 86.0 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:63.70 Median:64.00 stddev:14.80 (23.1%) stddev-sans-first:15.35 06:05:27 INFO - PROCESS | 1662 | Values: 55.0 51.0 51.0 53.0 73.0 84.0 83.0 82.0 52.0 53.0 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:72.00 stddev:2.50 (3.5%) stddev-sans-first:1.73 06:05:27 INFO - PROCESS | 1662 | Values: 66.0 69.0 75.0 72.0 73.0 72.0 72.0 70.0 73.0 72.0 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:85.00 stddev:7.74 (9.1%) stddev-sans-first:8.17 06:05:27 INFO - PROCESS | 1662 | Values: 86.0 86.0 86.0 83.0 84.0 92.0 101.0 102.0 83.0 79.0 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:147.20 Median:137.00 stddev:30.18 (22.0%) stddev-sans-first:31.79 06:05:27 INFO - PROCESS | 1662 | Values: 137.0 137.0 137.0 137.0 134.0 137.0 136.0 134.0 232.0 151.0 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.30 Median:167.00 stddev:21.39 (12.8%) stddev-sans-first:22.56 06:05:27 INFO - PROCESS | 1662 | Values: 167.0 164.0 167.0 166.0 166.0 169.0 165.0 166.0 169.0 234.0 06:05:27 INFO - PROCESS | 1662 | 06:05:27 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.00 Median:75.00 stddev:10.37 (13.8%) stddev-sans-first:10.84 06:05:27 INFO - PROCESS | 1662 | Values: 69.0 65.0 60.0 71.0 79.0 87.0 87.0 88.0 67.0 67.0 06:05:27 INFO - PROCESS | 1662 | -------- Summary: end -------- 06:05:27 INFO - PROCESS | 1662 | 06:05:28 INFO - TEST-INFO | 1662: exit 0 06:05:28 INFO - TEST-OK | kraken | took 59945ms 06:05:28 INFO - SUITE-END | took 1176s 06:05:28 INFO - Completed test suite (00:19:36) 06:05:28 INFO - javascript benchmark 06:05:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3359.874442061909, 3361.680640339168, 3368.7305015598754, 3374.8481318340678, 3411.897643070709, 5578.888422231553, 5668.234788303578, 5674.886502269952, 5717.313922329316, 5758.913616295759, 5715.571332150084, 5733.254676891998, 5792.333881603617, 5813.854653633662, 5847.619904706191, 5144.6655967362185, 5167.147420675584, 5230.34620672416, 5241.554467870235, 5371.785128594852, 5329.573634109262, 5359.062164121283, 5369.838904832861, 5379.838604841861, 5419.891602167954, 4346.500152482442, 5159.948400516003, 5212.817551385683, 5229.372475302988, 5237.3715154181555, 5173.0, 5204.42751297357, 5260.184671375943, 5293.338332708411, 5359.249705041298, 5173.715445650488, 5202.271681964528, 5222.712750798704, 5224.190250511177, 5303.5226829585135, 5765.654060756368, 5806.0, 5851.707414629294, 5854.414558544154, 5855.297364316267, 6098.522998456991, 6144.170536977495, 6171.166892469504, 6231.40801623845, 6258.4680302174365, 6267.0, 6317.778877739303, 6329.525285603552, 6343.238811342622, 6351.333110023427, 5884.735186916599, 5902.3507414184405, 5911.172435858984, 5919.260092488439, 5941.524678025791, 6294.716737746812, 6304.306526282106, 6346.619202847844, 6359.96820015897, 6373.075903993895, 6174.722137503823, 6182.103594978703, 6184.969075154595, 6198.411150940653, 6200.81397558074, 6268.874622507616, 6325.0, 6335.746570137143, 6348.936510634927, 6357.904631430532, 5999.640021598718, 6048.727807248684, 6058.0610005449225, 6127.281156068254, 6171.907421388682, 6206.0, 6297.968510157511, 6322.336154703782, 6335.68321583919, 6341.080543321193, 5007.173816320266, 5014.974925125424, 5019.974900125476, 5028.874278143076, 5065.468125846806, 4378.890527736832, 4440.023194897118, 4487.5288094749585, 4529.411176547093, 4533.229351010336, 4757.738324392191, 5674.233978412862, 5727.255456790674, 5731.914021289684, 5789.710514474259, 6290.937090629127, 6296.622202667854, 6315.431611155018, 6316.968415157895, 6337.461315788118, 5580.665160090408, 5636.97181514098, 5651.12407576818, 5724.713764311768, 5779.624324418899, 5450.1007333789485, 5476.096444086761, 5491.0116179088145, 5501.394846566875, 5524.281843360418, 6327.209098862642, 6333.556651034475, 6344.619322840645, 6352.9047064293145, 6355.618662880242, 5171.50870667286, 6239.032949892774, 6243.125962365363, 6287.842803929755, 6324.873502529832, 6270.905936411049, 6299.937000629935, 6312.936870631235, 6338.0, 6350.364963503613, 6211.472024877936, 6264.655443950626, 6265.15420418256, 6277.0, 6296.275928268308, 6271.749130034747, 6300.59046161998, 6303.936960630335, 6307.558470907176, 6316.463100636313, 5836.562257830595, 5983.192269043623, 6114.023656277781, 6159.630422174684, 6165.0444181152, 6197.721102550487, 6197.876042479215, 6215.0, 6228.283747369111, 6228.875422491434, 6027.849303767265, 6059.515238780798, 6075.0, 6084.269887613515, 6092.1619379790445, 5937.139114828239, 6050.818475445743, 6130.367562715148, 6135.386461354007, 6220.471259942956, 4660.860174194644, 5986.461218490183, 6006.699665016819, 6011.6693581853415, 6044.093385992136], "value": 5727.626739437426, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.2461237407775, 506.7567567567568, 508.6337836757533, 540.8566729816597, 551.8234165067178, 29716.851415742916, 30662.693373066264, 31613.68386316139, 39656.4137434503, 41112.0, 42351.78824105875, 42585.0, 42631.294748210115, 42771.358429623506, 42816.0, 9622.037796220391, 9796.167325777318, 9802.558884850163, 9835.065668761456, 9841.311108222428, 8824.823503529959, 8990.505522196276, 8994.820103597924, 9093.22707569854, 9714.22286217101, 9052.1853033227, 11704.824427633592, 11872.347020913847, 11906.880931190664, 12175.756484870297, 11911.523539058428, 11969.341686207255, 11987.280763154238, 11994.760104797942, 12003.759924801543, 8788.077251888524, 11541.653750387502, 11887.10846686502, 11969.521219151224, 11990.880091199064, 38899.611003889884, 41220.58779412197, 41317.0, 41317.173656527004, 41436.37845432321, 3493.423585108454, 3523.94714079289, 3526.594441639193, 3576.2847430513743, 3673.5040769496045, 31042.379152417052, 32201.03396898097, 32683.856065037846, 32963.670363296296, 33052.008439746845, 3319.68462996015, 3337.1156643489494, 3359.9160020999425, 3376.6792154745503, 3466.861325546975, 664765.0, 674949.0, 675133.6243318801, 675824.0, 677234.0, 60292.698536507036, 60326.396736032955, 60962.0, 61117.77764444686, 61128.38871611227, 60078.0, 60100.69949650311, 60118.0, 60122.0, 60218.69890650519, 16348.509544713677, 16564.585885352964, 16589.751153732705, 16890.40883569069, 16913.0, 12976.610701678965, 13064.738705225842, 13081.607551773463, 13104.737905241842, 13107.951363890867, 15253.923730381277, 15331.156786376856, 16578.336866525446, 16849.747253791204, 18551.832920693363, 7697.191794861571, 7749.806254843674, 7807.414443916729, 7823.0, 7877.133515313311, 903.9186473217442, 1012.5747186181821, 1050.3172937590475, 1079.8866119057543, 1081.5133190064503, 30447.934322298614, 30557.08328750141, 30674.386512270077, 31230.84384578108, 31234.843825780725, 1053.4206186597341, 1085.5440714899762, 1089.727568107973, 1124.145649306533, 1179.7640471905654, 15864.603384915239, 15891.920540397225, 15907.284172212278, 15936.601584960237, 16377.836221637632, 605408.0, 725966.0, 727457.0, 730683.0, 731016.0, 59169.70415147897, 59451.70274148688, 59574.702126489094, 59614.40385596088, 59641.105383419286, 1355.9254241016642, 1393.2754967416886, 1414.929253537319, 1418.865207805257, 1419.84381718013, 30146.944856929902, 30200.375954885556, 30221.546676800306, 30750.538741919336, 31008.0697579073, 49813.750931245115, 49914.750426247636, 50173.498265018345, 50281.49718502768, 50283.49716502788, 608780.0, 610643.0, 610752.0, 612212.0, 615187.9240603768, 22417.77582224157, 26623.467530649665, 26632.866835665696, 26788.464230714886, 26902.192934212006, 32661.0, 40552.59447405606, 48100.54894557711, 48598.0, 49267.50732492773, 38869.41695874621, 40572.59427405688, 40794.0, 40964.59035409608, 41818.1636367277, 38832.223355532165, 39855.40216896807, 40153.39769903512, 40170.79914600525, 40869.1391602585, 36989.44515832211, 37137.81431092935, 37139.442908356934, 38982.6101738979, 39464.01339966524, 30714.692853071185, 33752.66247337593, 34083.14792130217, 34751.13122171865, 35244.29511409809, 22402.66396004094, 25094.74905250924, 25839.483210336064, 26296.0, 26489.867550662122], "value": 20621.285450975414, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.4673457159976, 388.80365415465184, 389.78367006311487, 397.9920401591966, 407.91229885574614, 19478.0, 21412.678809817866, 22309.0, 22314.21900233492, 22514.324570262917, 18232.544186395346, 21496.677549836764, 22042.88978555109, 22225.777742222574, 22298.0, 5605.803796867101, 6131.294901086365, 6144.5698801083945, 6145.262568491765, 6159.260888693363, 4221.957780422203, 4477.910441791162, 6049.243844519435, 6483.902741458882, 6801.7959461216, 6511.088447617338, 6796.388325050744, 6805.863882722342, 6816.693248803816, 6819.045333653267, 6756.189257289107, 6769.559978601375, 6794.932050679479, 6798.150231221098, 6802.625855577941, 5806.042003069489, 5836.533077353802, 5887.4406931341455, 5889.087191485327, 5893.263342082239, 27262.318442038908, 27362.452750945067, 27592.0, 27751.861240693666, 27802.3049423764, 2609.5694210455254, 2629.6581444412295, 2695.528282550546, 2944.2399833344916, 3257.071734555664, 7170.426365890717, 7182.784516464514, 15931.203439828078, 15967.844889491751, 16057.91971040149, 1868.7944326124252, 1888.2352647177916, 1889.9321883135983, 1952.2288695965015, 1961.969965767969, 103189.0, 107413.46293268484, 107587.84824303548, 118099.0, 121580.0, 9477.573509192103, 11788.29270243788, 11844.526218951318, 11856.17006809515, 11982.783185110608, 14659.853401466062, 14807.111573305636, 14836.851631483547, 14840.332185051482, 14857.777133343008, 8602.569871506525, 8812.07473215316, 8834.911650883409, 8877.911220887709, 8913.420627659181, 8736.344774141964, 8756.25571826402, 8757.386982911135, 8861.202491775643, 8882.422642528216, 4513.526079761644, 4602.470715867651, 4657.930131048037, 4659.906801863944, 4660.627149828006, 1467.9706405871823, 1805.161940842769, 1833.8624603154549, 1901.4200668795872, 1936.3029309448586, 861.9870701939476, 882.960266787996, 889.6041261638638, 1045.749020235138, 1063.441693111123, 19924.30264940749, 20586.58826823485, 20934.267300644406, 21015.8949205253, 21133.68299475509, 954.8519979403204, 968.9612415503442, 1001.4692213127055, 1003.2726273451835, 1009.8232809258498, 10861.055287658459, 11167.553297868157, 11255.402613217791, 11353.772924541463, 11407.657770266906, 538416.0, 583863.0, 591532.0, 595266.0, 598979.0, 125199.0, 132911.0, 135462.0, 135572.0, 138475.0, 962.43216502264, 975.2588033094758, 987.2595553334999, 1009.8283291840552, 1011.8431643095332, 15994.920025399799, 17756.91121544384, 18907.905460472608, 19108.713369299192, 19171.61656766829, 46905.29642055304, 52597.0, 52864.67838304073, 54511.454885450636, 54660.0, 44071.55928440803, 44804.535750534036, 44848.77575612101, 72830.90753638593, 73085.6345718268, 7995.720149794729, 8363.414560980917, 8455.28130108923, 8464.44981076228, 8682.218600325748, 9856.950715246378, 13328.200307981551, 13472.32638368058, 13634.250116243702, 13642.590722278348, 11455.828162577734, 11535.8269625954, 11576.01603863681, 11648.068154547447, 11664.88335116638, 11328.603498877499, 11342.546298147981, 11348.943255283672, 11545.653630391102, 11559.537618495502, 11669.649910502698, 11692.35692036912, 11801.114916381133, 11838.704032398915, 11915.463804128662, 8331.750047498585, 8348.081711011906, 8386.0, 8427.62075706607, 8455.196756308142, 9426.905730942603, 10871.34771913656, 11319.037881780141, 11460.02589779878, 11534.423278835857], "value": 9810.947981013638, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1561.9843801561985, 1606.606381436548, 1774.6983012887808, 1894.526368407898, 1900.686386746187, 7907.604619769003, 8174.427790054699, 8563.700270490534, 8999.5050272235, 9013.684521041765, 8436.15638436156, 8674.178422531277, 8682.86975695365, 8804.20762131408, 8827.514486703229, 2286.8627882327073, 2367.5738367094004, 2368.0409434179187, 2370.4073981504625, 2372.7389987101405, 2075.30477290108, 2141.314779270634, 2283.5547068321684, 2318.3045086474076, 2351.482673811767, 1915.7030660247615, 2367.8342516023886, 2369.30105618842, 2371.727251366089, 2371.8102551795814, 2319.188284100568, 2342.367560758594, 2363.078399424226, 2363.1728894886824, 2363.6690863279155, 1934.4293433437028, 2271.7728227177317, 2330.685357476736, 2335.0426325206586, 2348.553774782803, 8946.821063578756, 9010.819783604291, 9024.458532488072, 9034.051424600453, 9070.86393704095, 5165.819196328148, 5316.3354580677415, 5317.229001794757, 5323.733813309319, 5326.121190003645, 3468.6531346865363, 3549.698275646573, 3610.7291953103622, 3619.8914032579064, 3624.7643903146213, 1048.1405247696891, 1076.1874784537624, 1088.8584484017026, 1098.961536346224, 1099.6700989703056, 11443.370614616193, 11540.422978851024, 11556.595519156874, 11569.942150289195, 11583.826242606368, 4381.16757816014, 4444.755538445352, 4542.545745425431, 4560.110778398181, 4583.358329833826, 8375.45559538628, 8745.038045814956, 9116.1339672731, 9236.214921731593, 9243.584038718274, 5177.4822517747925, 5182.144946083929, 5182.533571978464, 5187.066328060985, 5191.844244672666, 4666.32338310943, 4834.444038935497, 4841.12859685253, 4850.417949846041, 4855.077535268288, 3708.5735140458655, 3739.5886452490204, 3759.812009399519, 3794.734368594227, 3827.5981021992848, 1078.0890147825075, 1088.450332582041, 1121.2599684208383, 1130.8190689489807, 1137.4028634966708, 773.9651715672808, 788.5505262000604, 922.4280945813505, 935.4153653966271, 984.7685793838442, 6014.8195554133445, 6026.276846778414, 6176.907346389808, 6241.556201424779, 6287.402696743802, 954.6229239450378, 968.801395713877, 974.0892265731563, 986.4820968991194, 1010.7018429563296, 3921.117748506563, 4056.2090392373793, 4112.157007813391, 4159.1265834175165, 4163.625273725379, 10296.794064118676, 10727.892721072845, 10732.141428685687, 10917.617883374043, 10935.507902144423, 9849.655262065793, 10680.626178083885, 10775.245732798629, 10799.514021869034, 10807.67576972692, 8011.399145064143, 8289.04675962272, 8293.792655183668, 8430.915690843012, 8459.577021149042, 5998.49012833914, 6010.278766548042, 6109.877802443837, 6193.81418557444, 6335.683215839282, 9019.549022548978, 9835.950820245853, 10140.03669651382, 10161.593536258466, 10199.898001019896, 10251.436171010666, 10365.844512332473, 10397.11624511925, 10404.323718958243, 10409.0, 7494.951006058663, 7524.097108347033, 7573.242675732383, 7669.0, 7672.73145439907, 8261.793455163668, 8317.459365141247, 8339.666413343399, 8347.457415267989, 8399.916000839914, 7425.777226683208, 7548.320651141424, 7606.201348858512, 7669.884951725617, 7761.107472640719, 7312.670929808278, 7414.406847452299, 7422.443316751157, 7514.774556763306, 7590.0891892973195, 7025.192102908231, 7052.5063245574365, 7085.574865508086, 7094.53885497441, 7104.680289386886, 4921.753912304299, 5123.096769052185, 5149.382074151126, 5150.0472412603785, 5240.292560504436, 5013.348264725562, 5082.339295891658, 5094.719790411563, 5159.587233021424, 5299.973500132474], "value": 4581.224779617558, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.5844052089158, 318.73226489748606, 319.6116718187403, 324.37881457009826, 327.28814827749636, 4454.665900057496, 4689.976550117249, 5399.460053994599, 5421.810236641718, 5451.282845903537, 4765.927666275086, 5338.62629615927, 5433.179884738617, 5457.645253058558, 5469.945300546993, 3914.2954268231706, 3936.5276166860017, 4070.0, 4070.107400058972, 4181.874543763677, 3357.7145942594907, 4044.9191016179657, 4074.327735923569, 4087.6729861611, 4140.192662430827, 3629.691476224524, 3959.366501359783, 3962.4056391541353, 4000.8799736007823, 4092.5088989321325, 3392.728581713457, 3677.9771125880657, 3873.6513713765758, 4027.2549578328103, 4133.6693064554765, 3545.4504551794257, 3568.839402226906, 3579.8031108289033, 3584.462330650423, 3591.066322756102, 5248.0553500369915, 5280.260763493114, 5325.387580428262, 5329.920051199235, 5348.224507446437, 4477.507474177838, 4658.58072773452, 4734.668573199878, 4741.596964258042, 4745.335653008581, 5297.655652382621, 5609.9902017636805, 5612.971935140339, 5616.382197958222, 5659.490645841894, 3641.526601541809, 3965.722399432041, 3976.1848820991627, 3986.681065514752, 4001.339778936472, 4961.485048605751, 5584.263710781728, 5697.316322041382, 5887.558433117448, 5928.614640048383, 3674.889753307405, 3957.722959392815, 3979.3235150024566, 3990.2418540477215, 4017.819198136091, 3735.831887565066, 4071.2875246832277, 4079.551249362568, 4080.4287399764057, 4105.651019663303, 4113.572991934773, 4505.166544189336, 4705.835295764632, 4712.670113092051, 4725.669203155746, 4715.693479923794, 5622.184783206412, 5724.4561766632105, 5736.368999410061, 5807.593468457168, 1203.8976686981705, 1259.8677138900466, 1399.9090059146122, 1414.5826981040618, 1421.3461807568635, 731.1847290271415, 760.5474742528187, 813.6094674556247, 850.1795767084793, 866.9393142479965, 4987.426445958688, 5120.590352771769, 5182.708979797855, 5292.576593872481, 5310.176922577007, 5344.171653393731, 5617.606767526316, 5811.3316968548315, 5813.0, 5825.252424272701, 4947.406311242674, 5243.318368612056, 5307.920381194285, 5320.935812837448, 5387.622866399314, 2865.312325041975, 3038.255627371308, 3045.193023848682, 3056.205386599456, 3063.2495038715656, 6037.78867739627, 6050.939490605126, 6083.665398403042, 6120.460009096175, 6189.597676151036, 4369.80335884886, 5795.928158076226, 5947.186108236942, 5970.850728731816, 6103.8474038148515, 5741.598088133874, 5958.314793798769, 6017.3681763414215, 6040.184575082308, 6043.486303664238, 5219.451957544555, 5436.7281635918835, 5832.679202643895, 5896.582401215481, 5907.2911250650195, 4619.93070103942, 4951.830828269982, 5205.375354957429, 5667.319921609434, 5704.543636508986, 5133.281340612392, 5312.229726689686, 5971.671558064349, 6010.368911264254, 6088.4824789893355, 3786.6368107481835, 3893.8831835044994, 3963.4054891766464, 3966.2662407454714, 3993.181397813499, 4738.33663287147, 4802.655256528178, 5718.742656580547, 5773.04744717117, 5806.187133801187, 5601.60788744784, 5684.602077854511, 5691.23168372281, 5696.430356964271, 5753.884922301614, 4742.810287588596, 5422.620416570802, 5502.9174562380795, 5635.492805647511, 5713.286189060163, 4082.1222237770958, 4211.536730959653, 5053.797848086182, 5424.810131645373, 5437.646552974044, 4700.0, 4787.904241915073, 5291.76187071575, 5462.743568979077, 5562.471565201299, 4818.494058123987, 5467.0, 5493.0, 5494.752736126964, 5508.586855985737], "value": 4115.919143456017, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.3725527418783, 770.6069904348782, 784.7528028670969, 796.4703472190994, 837.6942416018152, 14610.926945365272, 14956.925215373907, 15016.39934402624, 15025.02337348073, 15070.0, 14754.188519631416, 14953.47662831801, 14958.252087395642, 14976.475823346185, 15058.54824355271, 691.4019373242135, 720.7225218290944, 720.805382546712, 725.2529894208973, 729.2925861913935, 711.2993701204309, 856.3919617071855, 894.7718331825375, 895.6059333893066, 899.2401420799414, 927.5686805635412, 940.7789169545151, 944.6693657219987, 944.9291303152256, 944.9905500944972, 937.9906200937971, 939.6617217801586, 939.9060093990546, 940.6707652321701, 943.0852073488705, 544.0, 569.8347479231018, 569.8774763425884, 574.5231457889942, 705.4427002667937, 14729.852701472955, 15347.769783453257, 15350.769738453931, 15362.923185384114, 15393.153376564285, 14648.560543183721, 15063.096214227182, 15084.472043478536, 15197.6200594986, 15207.847921520754, 11943.462544185533, 12170.452329645188, 12395.194312369757, 12408.937955310255, 12499.56251531201, 3840.135969406889, 3888.786116763577, 3974.2448934702315, 4085.3259212229946, 4114.5679703630985, 108196.91803081948, 109096.0, 109377.0, 109523.0, 113773.99999999917, 11637.418129093512, 11649.825252621218, 11717.765644687059, 11741.941290293666, 12260.570880019157, 24121.879390603284, 24124.87937560301, 24158.396040098785, 24173.39566510816, 24188.758112419004, 7954.125046244909, 8378.036525799489, 8520.659173633108, 8535.146485351415, 8606.913930860612, 4129.731567448166, 4588.908221835544, 4677.134730074947, 4681.368015317957, 4706.081636516717, 3455.1534873956043, 3531.788092714445, 3553.1117220694828, 3556.893293201156, 3559.2703495783303, 639.0510092512615, 645.7610684046932, 647.3914520350855, 659.1036190780535, 663.9568428052161, 606.902895536712, 659.152988409891, 692.8152858611729, 709.7480394459996, 715.5670819154441, 8367.534761087505, 9302.767430814283, 9390.62437502506, 9440.811183776286, 9596.424214547149, 575.9078547432391, 618.7215752911208, 650.7331993882485, 668.7024274198033, 670.1790306874041, 4192.790360481964, 4341.261985462479, 4559.338895860148, 4626.4910859805395, 4672.76636168197, 143640.0, 152283.0, 153888.0, 155045.2247738754, 157390.0, 122769.0, 126222.36888815498, 126454.41273523618, 127141.0, 130417.34791325983, 113768.43115784368, 116690.41654791671, 119257.0, 119462.40268798775, 119540.8045919547, 8898.42160259581, 9069.77325566866, 9488.098630630078, 9548.618055277711, 9655.034496550288, 60897.0, 61377.69311153416, 61752.38247617646, 63042.369576305486, 63499.682501587195, 88279.55860220658, 93967.0, 94103.0, 94660.05339946512, 96795.51602241944, 11185.38480383554, 13224.669383265495, 13354.732905342033, 13510.392032358372, 13904.165750055028, 15335.923320383326, 15390.230488475756, 15702.450414235904, 15830.762538562161, 15937.12345820991, 13971.0, 14029.579112626638, 14430.639234018814, 14470.348834302693, 14841.480548180762, 13954.860451395356, 14263.286835657967, 14539.709205816036, 14567.198804065878, 14808.25958702082, 13598.456061757815, 13677.658058548615, 14065.507707230197, 14336.56990290293, 14503.564893053226, 11248.987591116838, 11261.436928153396, 11376.488058037574, 11854.466549005143, 11972.820407693718, 11056.568373925818, 11601.651950441501, 11691.824622630496, 11838.526458941546, 11906.166568340133], "value": 7985.835310050965, "unit": "score"}], "name": "dromaeo_css", "value": 7475.431103017121, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 114.0, 112.0, 114.0, 113.0, 113.0, 113.0, 114.0, 113.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 107.0, 156.0, 114.0, 138.0, 138.0, 127.0, 145.0, 107.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 183.0, 182.0, 182.0, 188.0, 184.0, 182.0, 180.0, 180.0, 184.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 95.0, 84.0, 114.0, 58.0, 56.0, 66.0, 60.0, 83.0, 57.0], "value": 75.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 92.0, 94.0, 71.0, 70.0, 67.0, 96.0, 69.0, 68.0, 97.0], "value": 80.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 84.0, 87.0, 93.0, 135.0, 84.0, 87.0, 84.0], "value": 91.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 92.0, 94.0, 93.0, 93.0, 93.0, 93.0, 93.0, 92.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 99.0, 132.0, 120.0, 84.0, 99.0, 131.0, 83.0, 86.0], "value": 100.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 51.0, 51.0, 53.0, 73.0, 84.0, 83.0, 82.0, 52.0, 53.0], "value": 63.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 75.0, 72.0, 73.0, 72.0, 72.0, 70.0, 73.0, 72.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 83.0, 84.0, 92.0, 101.0, 102.0, 83.0, 79.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 137.0, 137.0, 134.0, 137.0, 136.0, 134.0, 232.0, 151.0], "value": 147.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 167.0, 166.0, 166.0, 169.0, 165.0, 166.0, 169.0, 234.0], "value": 173.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 60.0, 71.0, 79.0, 87.0, 87.0, 88.0, 67.0, 67.0], "value": 74.0, "unit": "score"}], "name": "kraken", "value": 1481.6, "alertThreshold": 2.0}]} 06:05:28 INFO - Return code: 0 06:05:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:05:28 INFO - # TBPL SUCCESS # 06:05:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:05:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:05:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:05:28 INFO - 2016-07-19 06:05:28.820 screenresolution[1666:10062] starting screenresolution argv=screenresolution get 06:05:28 INFO - 2016-07-19 06:05:28.823 screenresolution[1666:10062] Display 0: 1600x1200x32@60 06:05:28 INFO - 2016-07-19 06:05:28.829 screenresolution[1667:10065] starting screenresolution argv=screenresolution list 06:05:28 INFO - Available Modes on Display 0 06:05:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:05:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:05:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:05:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:05:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:05:29 INFO - Intel Iris: 06:05:29 INFO - Chipset Model: Intel Iris 06:05:29 INFO - Type: GPU 06:05:29 INFO - Bus: Built-In 06:05:29 INFO - VRAM (Dynamic, Max): 1536 MB 06:05:29 INFO - Vendor: Intel (0x8086) 06:05:29 INFO - Device ID: 0x0a2e 06:05:29 INFO - Revision ID: 0x0009 06:05:29 INFO - Displays: 06:05:29 INFO - Display: 06:05:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:05:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:05:29 INFO - Display Serial Number: 1600x1200 60 06:05:29 INFO - Main Display: Yes 06:05:29 INFO - Mirror: Off 06:05:29 INFO - Online: Yes 06:05:29 INFO - Rotation: Supported 06:05:29 INFO - Return code: 0 06:05:29 INFO - Running post-action listener: _resource_record_post_action 06:05:29 INFO - ##### 06:05:29 INFO - ##### Finished run-tests step (success) 06:05:29 INFO - ##### 06:05:29 INFO - Running post-run listener: _resource_record_post_run 06:05:29 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 37983744; Write bytes: 705832960; Read time: 389; Write time: 1833 06:05:29 INFO - TinderboxPrint: CPU usage
53.3% 06:05:29 INFO - TinderboxPrint: I/O read bytes / time
37,983,744 / 389 06:05:29 INFO - TinderboxPrint: I/O write bytes / time
705,832,960 / 1,833 06:05:29 INFO - TinderboxPrint: CPU idle
2,264.6 (46.7%) 06:05:29 INFO - TinderboxPrint: CPU system
965.3 (19.9%) 06:05:29 INFO - TinderboxPrint: CPU user
1,619.9 (33.4%) 06:05:29 INFO - TinderboxPrint: Swap in / out
445,558,784 / 0 06:05:29 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 393060864; Write bytes: 387204608; Read time: 29423; Write time: 680 06:05:29 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 37021184; Write bytes: 295543296; Read time: 374; Write time: 1105 06:05:29 INFO - Running post-run listener: _upload_blobber_files 06:05:29 INFO - Blob upload gear active. 06:05:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:05:29 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 06:05:29 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'] 06:05:29 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 06:05:30 INFO - (blobuploader) - INFO - Open directory for files ... 06:05:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:05:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:05:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:05:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:05:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:05:30 INFO - (blobuploader) - INFO - Done attempting. 06:05:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:05:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:05:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:05:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:05:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:05:32 INFO - (blobuploader) - INFO - Done attempting. 06:05:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:05:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:05:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:05:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:05:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:05:34 INFO - (blobuploader) - INFO - Done attempting. 06:05:34 INFO - (blobuploader) - INFO - Iteration through files over. 06:05:34 INFO - Return code: 0 06:05:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:05:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:05:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8244621aca8bd9485eef1e8175787d8a08565e9d2f9c002e3cbddbcd3f518195cd6860c9baddaf53d4653e9dc0abff4ceacc9242768d71c5de53646457239f31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99c68f6d76328dea81b20ea19c51e413f5a19aca25dbbea31076d5143c28e8007198879b40232eae94d19996e4c6762afd94e2c32e120eb3351a65101e306237", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b719551a4c619e517714d517e3c0dbdb790b7423be8badc758b27aa76c9731ecf0eaf8a7b498c8774bc7556a7a5bfe897100c22c7338a12f2c113f71f53019a"} 06:05:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:05:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:05:34 INFO - Contents: 06:05:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8244621aca8bd9485eef1e8175787d8a08565e9d2f9c002e3cbddbcd3f518195cd6860c9baddaf53d4653e9dc0abff4ceacc9242768d71c5de53646457239f31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99c68f6d76328dea81b20ea19c51e413f5a19aca25dbbea31076d5143c28e8007198879b40232eae94d19996e4c6762afd94e2c32e120eb3351a65101e306237", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b719551a4c619e517714d517e3c0dbdb790b7423be8badc758b27aa76c9731ecf0eaf8a7b498c8774bc7556a7a5bfe897100c22c7338a12f2c113f71f53019a"} 06:05:34 INFO - Running post-run listener: copy_logs_to_upload_dir 06:05:34 INFO - Copying logs to upload dir... 06:05:34 INFO - mkdir: /builds/slave/test/build/upload/logs 06:05:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.875178 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-19 06:05:35.011345) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 06:05:35.015332) ========= 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.PBmqlZHLaN/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.05wQR2NITL/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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/8244621aca8bd9485eef1e8175787d8a08565e9d2f9c002e3cbddbcd3f518195cd6860c9baddaf53d4653e9dc0abff4ceacc9242768d71c5de53646457239f31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99c68f6d76328dea81b20ea19c51e413f5a19aca25dbbea31076d5143c28e8007198879b40232eae94d19996e4c6762afd94e2c32e120eb3351a65101e306237", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b719551a4c619e517714d517e3c0dbdb790b7423be8badc758b27aa76c9731ecf0eaf8a7b498c8774bc7556a7a5bfe897100c22c7338a12f2c113f71f53019a"} build_url:https://queue.taskcluster.net/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010935 build_url: 'https://queue.taskcluster.net/v1/task/CR-Zy1xTTQ-GKebcXXw5mA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8244621aca8bd9485eef1e8175787d8a08565e9d2f9c002e3cbddbcd3f518195cd6860c9baddaf53d4653e9dc0abff4ceacc9242768d71c5de53646457239f31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99c68f6d76328dea81b20ea19c51e413f5a19aca25dbbea31076d5143c28e8007198879b40232eae94d19996e4c6762afd94e2c32e120eb3351a65101e306237", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b719551a4c619e517714d517e3c0dbdb790b7423be8badc758b27aa76c9731ecf0eaf8a7b498c8774bc7556a7a5bfe897100c22c7338a12f2c113f71f53019a"}' ========= master_lag: 0.73 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 06:05:35.751891) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 06:05:35.752246) ========= 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.PBmqlZHLaN/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.05wQR2NITL/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.005204 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 06:05:35.779305) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 06:05:35.779668) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 06:05:35.780029) ========= ========= Total master_lag: 0.85 =========