builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0385 starttime: 1465288635.84 results: success (0) buildid: 20160606120332 builduid: 394ff36afaaf47b0a5ccdc9e3ac607e3 revision: 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 01:37:15.836663) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 01:37:15.837098) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 01:37:15.837369) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 01:37:15.861353) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:37:15.861693) ========= 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.UniNK3SeP2/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.nKiiX8EZFK/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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-06-07 01:37:15-- 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% 187M=0s 2016-06-07 01:37:16 (187 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.408171 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:37:16.291599) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:37:16.291932) ========= 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.UniNK3SeP2/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.nKiiX8EZFK/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.026120 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:37:16.342634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-07 01:37:16.342995) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UniNK3SeP2/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.nKiiX8EZFK/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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-06-07 01:37:16,424 truncating revision to first 12 chars 2016-06-07 01:37:16,425 Setting DEBUG logging. 2016-06-07 01:37:16,425 attempt 1/10 2016-06-07 01:37:16,425 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/46d72a56c57d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-07 01:37:17,504 unpacking tar archive at: mozilla-beta-46d72a56c57d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.351385 ========= master_lag: 0.31 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-07 01:37:18.005038) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 01:37:18.005465) ========= script_repo_revision: 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 01:37:18.005915) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 01:37:18.006190) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 01:37:18.024606) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-06-07 01:37:18.025031) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UniNK3SeP2/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.nKiiX8EZFK/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 01:37:18 INFO - MultiFileLogger online at 20160607 01:37:18 in /builds/slave/test 01:37:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:37:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:37:18 INFO - {'append_to_log': False, 01:37:18 INFO - 'base_work_dir': '/builds/slave/test', 01:37:18 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:37:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:37:18 INFO - 'branch': 'Mozilla-Beta', 01:37:18 INFO - 'buildbot_json_path': 'buildprops.json', 01:37:18 INFO - 'config_files': ('talos/mac_config.py',), 01:37:18 INFO - 'default_actions': ('clobber', 01:37:18 INFO - 'read-buildbot-config', 01:37:18 INFO - 'download-and-extract', 01:37:18 INFO - 'populate-webroot', 01:37:18 INFO - 'create-virtualenv', 01:37:18 INFO - 'install', 01:37:18 INFO - 'run-tests'), 01:37:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:37:18 INFO - 'download_minidump_stackwalk': True, 01:37:18 INFO - 'download_symbols': 'ondemand', 01:37:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:37:18 INFO - 'tooltool.py': '/tools/tooltool.py', 01:37:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:37:18 INFO - '/tools/misc-python/virtualenv.py')}, 01:37:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:37:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:37:18 INFO - 'installer_path': 'installer.exe', 01:37:18 INFO - 'log_level': 'info', 01:37:18 INFO - 'log_name': 'talos', 01:37:18 INFO - 'log_to_console': True, 01:37:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:37:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:37:18 INFO - 'opt_config_files': (), 01:37:18 INFO - 'pip_index': False, 01:37:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:37:18 INFO - 'cmd': ('bash', 01:37:18 INFO - '-c', 01:37:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:37:18 INFO - 'enabled': True, 01:37:18 INFO - 'halt_on_failure': False, 01:37:18 INFO - 'name': 'check_screen_resolution'},), 01:37:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:37:18 INFO - 'cmd': ('bash', 01:37:18 INFO - '-c', 01:37:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:37:18 INFO - 'enabled': True, 01:37:18 INFO - 'halt_on_failure': False, 01:37:18 INFO - 'name': 'check_screen_resolution'},), 01:37:18 INFO - 'run_cmd_checks_enabled': True, 01:37:18 INFO - 'sps_profile': False, 01:37:18 INFO - 'sps_profile_interval': 0, 01:37:18 INFO - 'suite': 'dromaeojs', 01:37:18 INFO - 'system_bits': '32', 01:37:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:37:18 INFO - 'title': 't-yosemite-r7-0385', 01:37:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:37:18 INFO - 'use_talos_json': True, 01:37:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:37:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:37:18 INFO - 'work_dir': 'build'} 01:37:18 INFO - ##### 01:37:18 INFO - ##### Running clobber step. 01:37:18 INFO - ##### 01:37:18 INFO - Running pre-action listener: _resource_record_pre_action 01:37:18 INFO - Running main action method: clobber 01:37:18 INFO - rmtree: /builds/slave/test/build 01:37:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:37:20 INFO - Running post-action listener: _resource_record_post_action 01:37:20 INFO - ##### 01:37:20 INFO - ##### Running read-buildbot-config step. 01:37:20 INFO - ##### 01:37:20 INFO - Running pre-action listener: _resource_record_pre_action 01:37:20 INFO - Running main action method: read_buildbot_config 01:37:20 INFO - Using buildbot properties: 01:37:20 INFO - { 01:37:20 INFO - "project": "", 01:37:20 INFO - "product": "firefox", 01:37:20 INFO - "script_repo_revision": "production", 01:37:20 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 01:37:20 INFO - "repository": "", 01:37:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 01:37:20 INFO - "buildid": "20160606120332", 01:37:20 INFO - "pgo_build": "False", 01:37:20 INFO - "basedir": "/builds/slave/test", 01:37:20 INFO - "buildnumber": 33, 01:37:20 INFO - "slavename": "t-yosemite-r7-0385", 01:37:20 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 01:37:20 INFO - "platform": "yosemite_r7", 01:37:20 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 01:37:20 INFO - "branch": "mozilla-beta", 01:37:20 INFO - "repo_path": "releases/mozilla-beta", 01:37:20 INFO - "revision": "46d72a56c57dafb4dc1061d4741a3e1181ac3d68", 01:37:20 INFO - "stage_platform": "macosx64", 01:37:20 INFO - "builduid": "394ff36afaaf47b0a5ccdc9e3ac607e3", 01:37:20 INFO - "slavebuilddir": "test" 01:37:20 INFO - } 01:37:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 01:37:20 INFO - Running post-action listener: _resource_record_post_action 01:37:20 INFO - ##### 01:37:20 INFO - ##### Running download-and-extract step. 01:37:20 INFO - ##### 01:37:20 INFO - Running pre-action listener: _resource_record_pre_action 01:37:20 INFO - Running main action method: download_and_extract 01:37:20 INFO - mkdir: /builds/slave/test/build/tests 01:37:20 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')]} 01:37:20 INFO - https://queue.taskcluster.net/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:37:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 01:37:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 01:37:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 01:37:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 01:37:21 INFO - Downloaded 1165 bytes. 01:37:21 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 01:37:21 INFO - Using the following test package requirements: 01:37:21 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 01:37:21 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:37:21 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 01:37:21 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:37:21 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 01:37:21 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 01:37:21 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:37:21 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 01:37:21 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 01:37:21 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:37:21 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 01:37:21 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:37:21 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 01:37:21 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:37:21 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 01:37:21 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:37:21 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 01:37:21 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 01:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:37:21 INFO - https://queue.taskcluster.net/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:37:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:37:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:37:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 01:37:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 01:37:22 INFO - Downloaded 18292404 bytes. 01:37:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:37:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:37:25 INFO - Return code: 0 01:37:25 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 01:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:37:25 INFO - https://queue.taskcluster.net/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:37:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:37:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:37:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 01:37:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 01:37:29 INFO - Downloaded 18292404 bytes. 01:37:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:37:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:37:32 INFO - Return code: 0 01:37:32 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')]} 01:37:32 INFO - https://queue.taskcluster.net/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:37:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 01:37:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 01:37:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 01:37:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 01:37:33 INFO - Downloaded 10840145 bytes. 01:37:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:37:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 01:37:34 INFO - Return code: 0 01:37:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:37:34 INFO - https://queue.taskcluster.net/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 01:37:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:37:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:37:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 01:37:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 01:37:36 INFO - Downloaded 86246253 bytes. 01:37:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:37:36 INFO - mkdir: /builds/slave/test/properties 01:37:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:37:36 INFO - Writing to file /builds/slave/test/properties/build_url 01:37:36 INFO - Contents: 01:37:36 INFO - build_url:https://queue.taskcluster.net/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:37:37 INFO - Running post-action listener: _resource_record_post_action 01:37:37 INFO - Running post-action listener: set_extra_try_arguments 01:37:37 INFO - ##### 01:37:37 INFO - ##### Running populate-webroot step. 01:37:37 INFO - ##### 01:37:37 INFO - Running pre-action listener: _resource_record_pre_action 01:37:37 INFO - Running main action method: populate_webroot 01:37:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:37:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:37:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:37:37 INFO - '--tppagecycles', 01:37:37 INFO - '7'], 01:37:37 INFO - 'tests': ['tsvgm']}}, 01:37:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:37:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:37:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:37:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:37:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:37:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:37:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:37 INFO - 'talos_options': ['--disable-e10s'], 01:37:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:37:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:37:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:37 INFO - 'talos_options': ['--disable-e10s'], 01:37:37 INFO - 'tests': ['damp', 'tps']}, 01:37:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:37 INFO - 'tests': ['damp', 'tps']}, 01:37:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:37:37 INFO - 'tests': ['dromaeo_dom']}, 01:37:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:37:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:37:37 INFO - 'tests': ['a11yr', 01:37:37 INFO - 'ts_paint', 01:37:37 INFO - 'tpaint', 01:37:37 INFO - 'sessionrestore', 01:37:37 INFO - 'sessionrestore_no_auto_restore', 01:37:37 INFO - 'tabpaint']}, 01:37:37 INFO - 'other-e10s': {'tests': ['a11yr', 01:37:37 INFO - 'ts_paint', 01:37:37 INFO - 'tpaint', 01:37:37 INFO - 'sessionrestore', 01:37:37 INFO - 'sessionrestore_no_auto_restore', 01:37:37 INFO - 'tabpaint']}, 01:37:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:37:37 INFO - 'ts_paint', 01:37:37 INFO - 'tpaint', 01:37:37 INFO - 'sessionrestore', 01:37:37 INFO - 'sessionrestore_no_auto_restore', 01:37:37 INFO - 'tabpaint']}, 01:37:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:37:37 INFO - 'ts_paint', 01:37:37 INFO - 'tpaint', 01:37:37 INFO - 'sessionrestore', 01:37:37 INFO - 'sessionrestore_no_auto_restore', 01:37:37 INFO - 'tabpaint']}, 01:37:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:37:37 INFO - 'tests': ['a11yr', 01:37:37 INFO - 'ts_paint', 01:37:37 INFO - 'tpaint', 01:37:37 INFO - 'sessionrestore', 01:37:37 INFO - 'sessionrestore_no_auto_restore', 01:37:37 INFO - 'tabpaint']}, 01:37:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:37:37 INFO - 'tests': ['a11yr', 01:37:37 INFO - 'ts_paint', 01:37:37 INFO - 'tpaint', 01:37:37 INFO - 'sessionrestore', 01:37:37 INFO - 'sessionrestore_no_auto_restore', 01:37:37 INFO - 'tabpaint']}, 01:37:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:37:37 INFO - 'tests': ['tsvgx', 01:37:37 INFO - 'tsvgr_opacity', 01:37:37 INFO - 'tart', 01:37:37 INFO - 'tscrollx', 01:37:37 INFO - 'cart']}, 01:37:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:37:37 INFO - 'tsvgr_opacity', 01:37:37 INFO - 'tart', 01:37:37 INFO - 'tscrollx', 01:37:37 INFO - 'cart']}, 01:37:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:37 INFO - 'talos_options': ['--disable-e10s'], 01:37:37 INFO - 'tests': ['tp5o']}, 01:37:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:37 INFO - 'tests': ['tp5o']}, 01:37:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:37 INFO - 'talos_options': ['--disable-e10s', 01:37:37 INFO - '--xperf_path', 01:37:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:37:37 INFO - 'tests': ['tp5n']}, 01:37:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:37 INFO - 'talos_options': ['--xperf_path', 01:37:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:37:37 INFO - 'tests': ['tp5n']}}, 01:37:37 INFO - 'talos.zip': {'path': '', 01:37:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:37:37 INFO - Running post-action listener: _resource_record_post_action 01:37:37 INFO - ##### 01:37:37 INFO - ##### Running create-virtualenv step. 01:37:37 INFO - ##### 01:37:37 INFO - Running pre-action listener: _resource_record_pre_action 01:37:37 INFO - Running main action method: create_virtualenv 01:37:37 INFO - Creating virtualenv /builds/slave/test/build/venv 01:37:37 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 01:37:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:37:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:37:37 INFO - Using real prefix '/tools/python27' 01:37:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:37:38 INFO - Installing distribute.............................................................................................................................................................................................done. 01:37:41 INFO - Installing pip.................done. 01:37:41 INFO - Return code: 0 01:37:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:37: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')]} 01:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37: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')]} 01:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:41 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 0x106515a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106525420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f38da2ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b4e00>, '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 0x10621ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063afb20>, '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.UniNK3SeP2/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.nKiiX8EZFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 01:37:41 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 01:37:41 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 01:37:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UniNK3SeP2/Render', 01:37:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:41 INFO - 'HOME': '/Users/cltbld', 01:37:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:41 INFO - 'LOGNAME': 'cltbld', 01:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:41 INFO - 'MOZ_NO_REMOTE': '1', 01:37:41 INFO - 'NO_EM_RESTART': '1', 01:37:41 INFO - 'PAGER': '/bin/cat', 01:37:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:41 INFO - 'PWD': '/builds/slave/test', 01:37:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:41 INFO - 'SHELL': '/bin/bash', 01:37:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nKiiX8EZFK/Listeners', 01:37:41 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 01:37:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:41 INFO - 'USER': 'cltbld', 01:37:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:41 INFO - 'XPC_FLAGS': '0x0', 01:37:41 INFO - 'XPC_SERVICE_NAME': '0', 01:37:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:41 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 01:37:41 INFO - Cleaning up... 01:37:41 INFO - Return code: 0 01:37:41 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:37: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')]} 01:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37: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')]} 01:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:41 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106515a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106525420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f38da2ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b4e00>, '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 0x10621ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063afb20>, '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.UniNK3SeP2/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.nKiiX8EZFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 01:37:41 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>=0.7.1'] in /builds/slave/test/build 01:37:41 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>=0.7.1 01:37:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UniNK3SeP2/Render', 01:37:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:41 INFO - 'HOME': '/Users/cltbld', 01:37:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:41 INFO - 'LOGNAME': 'cltbld', 01:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:41 INFO - 'MOZ_NO_REMOTE': '1', 01:37:41 INFO - 'NO_EM_RESTART': '1', 01:37:41 INFO - 'PAGER': '/bin/cat', 01:37:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:41 INFO - 'PWD': '/builds/slave/test', 01:37:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:41 INFO - 'SHELL': '/bin/bash', 01:37:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nKiiX8EZFK/Listeners', 01:37:41 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 01:37:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:41 INFO - 'USER': 'cltbld', 01:37:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:41 INFO - 'XPC_FLAGS': '0x0', 01:37:41 INFO - 'XPC_SERVICE_NAME': '0', 01:37:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:41 INFO - Downloading/unpacking psutil>=0.7.1 01:37:41 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 01:37:41 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 01:37:41 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 01:37:41 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 01:37:41 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 01:37:41 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 01:37:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:37:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:37:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:37:44 INFO - Installing collected packages: psutil 01:37:44 INFO - Running setup.py install for psutil 01:37:44 INFO - building 'psutil._psutil_osx' extension 01:37:44 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 01:37:45 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 01:37:45 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 01:37:45 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 01:37:45 INFO - building 'psutil._psutil_posix' extension 01:37:45 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 01:37:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:37:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:37:45 INFO - ^ 01:37:45 INFO - 1 warning generated. 01:37:45 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 01:37:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:37:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:37:45 INFO - Successfully installed psutil 01:37:45 INFO - Cleaning up... 01:37:46 INFO - Return code: 0 01:37:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:37:46 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')]} 01:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:46 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')]} 01:37:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:46 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106515a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106525420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f38da2ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b4e00>, '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 0x10621ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063afb20>, '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.UniNK3SeP2/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.nKiiX8EZFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 01:37:46 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.0.0'] in /builds/slave/test/build 01:37:46 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.0.0 01:37:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UniNK3SeP2/Render', 01:37:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:46 INFO - 'HOME': '/Users/cltbld', 01:37:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:46 INFO - 'LOGNAME': 'cltbld', 01:37:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:46 INFO - 'MOZ_NO_REMOTE': '1', 01:37:46 INFO - 'NO_EM_RESTART': '1', 01:37:46 INFO - 'PAGER': '/bin/cat', 01:37:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:46 INFO - 'PWD': '/builds/slave/test', 01:37:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:46 INFO - 'SHELL': '/bin/bash', 01:37:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nKiiX8EZFK/Listeners', 01:37:46 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 01:37:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:46 INFO - 'USER': 'cltbld', 01:37:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:46 INFO - 'XPC_FLAGS': '0x0', 01:37:46 INFO - 'XPC_SERVICE_NAME': '0', 01:37:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:46 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:37:46 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 01:37:46 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 01:37:46 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 01:37:46 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 01:37:46 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 01:37:46 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 01:37:48 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:37:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:37:48 INFO - Installing collected packages: mozsystemmonitor 01:37:48 INFO - Running setup.py install for mozsystemmonitor 01:37:49 INFO - Successfully installed mozsystemmonitor 01:37:49 INFO - Cleaning up... 01:37:49 INFO - Return code: 0 01:37:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:37: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')]} 01:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37: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')]} 01:37:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37: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', '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 0x106515a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106525420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f38da2ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b4e00>, '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 0x10621ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063afb20>, '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.UniNK3SeP2/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.nKiiX8EZFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 01:37: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:37: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 blobuploader==1.2.4 01:37:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UniNK3SeP2/Render', 01:37:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:49 INFO - 'HOME': '/Users/cltbld', 01:37:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:49 INFO - 'LOGNAME': 'cltbld', 01:37:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:49 INFO - 'MOZ_NO_REMOTE': '1', 01:37:49 INFO - 'NO_EM_RESTART': '1', 01:37:49 INFO - 'PAGER': '/bin/cat', 01:37:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:49 INFO - 'PWD': '/builds/slave/test', 01:37:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:49 INFO - 'SHELL': '/bin/bash', 01:37:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nKiiX8EZFK/Listeners', 01:37:49 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 01:37:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:49 INFO - 'USER': 'cltbld', 01:37:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:49 INFO - 'XPC_FLAGS': '0x0', 01:37:49 INFO - 'XPC_SERVICE_NAME': '0', 01:37:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:49 INFO - Downloading/unpacking blobuploader==1.2.4 01:37:49 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 01:37:49 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 01:37:49 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 01:37:49 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 01:37:49 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 01:37:49 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 01:37:51 INFO - Downloading blobuploader-1.2.4.tar.gz 01:37:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:37:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:37:51 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 01:37:51 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 01:37:51 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 01:37:51 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 01:37:51 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 01:37:51 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 01:37:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:37:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:37:52 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 01:37:52 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 01:37:52 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 01:37:52 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 01:37:52 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 01:37:52 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 01:37:52 INFO - Downloading docopt-0.6.1.tar.gz 01:37:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:37:53 INFO - Installing collected packages: blobuploader, requests, docopt 01:37:53 INFO - Running setup.py install for blobuploader 01:37:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:37:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:37:53 INFO - Running setup.py install for requests 01:37:53 INFO - Running setup.py install for docopt 01:37:53 INFO - Successfully installed blobuploader requests docopt 01:37:53 INFO - Cleaning up... 01:37:53 INFO - Return code: 0 01:37:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:37:53 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')]} 01:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:53 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')]} 01:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:53 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 0x106515a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106525420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f38da2ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b4e00>, '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 0x10621ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063afb20>, '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.UniNK3SeP2/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.nKiiX8EZFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 01:37:53 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 01:37:53 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 01:37:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UniNK3SeP2/Render', 01:37:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:53 INFO - 'HOME': '/Users/cltbld', 01:37:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:53 INFO - 'LOGNAME': 'cltbld', 01:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:53 INFO - 'MOZ_NO_REMOTE': '1', 01:37:53 INFO - 'NO_EM_RESTART': '1', 01:37:53 INFO - 'PAGER': '/bin/cat', 01:37:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:53 INFO - 'PWD': '/builds/slave/test', 01:37:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:53 INFO - 'SHELL': '/bin/bash', 01:37:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nKiiX8EZFK/Listeners', 01:37:53 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 01:37:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:53 INFO - 'USER': 'cltbld', 01:37:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:53 INFO - 'XPC_FLAGS': '0x0', 01:37:53 INFO - 'XPC_SERVICE_NAME': '0', 01:37:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:37:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-NOZVab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:37:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Sdyg0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:37:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-sVerEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:37:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-mauY51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:37:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-n9ATyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:37:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-rbPnlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:37:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-SY3lj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:37:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-HGyiHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:37:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-MxqHzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:37:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-vn4tyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:37:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-JEAqJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:37:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-oLCBII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:37:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-gZLsC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:37:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-9NLaLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-tdoUR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:37:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-OVnacb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:37:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-cTEQ15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:37:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:37:55 INFO - Running setup.py install for manifestparser 01:37:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:37:56 INFO - Running setup.py install for mozcrash 01:37:56 INFO - Running setup.py install for mozdebug 01:37:56 INFO - Running setup.py install for mozdevice 01:37:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:37:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:37:56 INFO - Running setup.py install for mozfile 01:37:56 INFO - Running setup.py install for mozhttpd 01:37:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:37:56 INFO - Running setup.py install for mozinfo 01:37:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:37:57 INFO - Running setup.py install for mozInstall 01:37:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:37:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:37:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:37:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:37:57 INFO - Running setup.py install for mozleak 01:37:57 INFO - Running setup.py install for mozlog 01:37:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:37:57 INFO - Running setup.py install for moznetwork 01:37:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:37:57 INFO - Running setup.py install for mozprocess 01:37:57 INFO - Running setup.py install for mozprofile 01:37:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:37:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:37:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:37:57 INFO - Running setup.py install for mozrunner 01:37:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:37:58 INFO - Running setup.py install for mozscreenshot 01:37:58 INFO - Running setup.py install for moztest 01:37:58 INFO - Running setup.py install for mozversion 01:37:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:37:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:37:58 INFO - Cleaning up... 01:37:58 INFO - Return code: 0 01:37:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:37:58 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')]} 01:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:58 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')]} 01:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:58 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 0x106515a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106525420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f38da2ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b4e00>, '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 0x10621ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063afb20>, '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.UniNK3SeP2/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.nKiiX8EZFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 01:37:58 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 01:37:58 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 01:37:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UniNK3SeP2/Render', 01:37:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:58 INFO - 'HOME': '/Users/cltbld', 01:37:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:58 INFO - 'LOGNAME': 'cltbld', 01:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:58 INFO - 'MOZ_NO_REMOTE': '1', 01:37:58 INFO - 'NO_EM_RESTART': '1', 01:37:58 INFO - 'PAGER': '/bin/cat', 01:37:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:58 INFO - 'PWD': '/builds/slave/test', 01:37:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:58 INFO - 'SHELL': '/bin/bash', 01:37:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nKiiX8EZFK/Listeners', 01:37:58 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 01:37:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:58 INFO - 'USER': 'cltbld', 01:37:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:58 INFO - 'XPC_FLAGS': '0x0', 01:37:58 INFO - 'XPC_SERVICE_NAME': '0', 01:37:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:37:58 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-AqiBpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:37:58 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)) 01:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:37:58 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Pb6gAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:37:59 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)) 01:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:37:59 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-PhS7UJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:37:59 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)) 01:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:37:59 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-hPmjB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:37:59 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)) 01:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:37:59 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-ZQ0hHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:37:59 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)) 01:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:37:59 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Qc666F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:37:59 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)) 01:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:37:59 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-nXR_w6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:37:59 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)) 01:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:37:59 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-2n43sQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:37:59 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)) 01:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:37:59 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-9W2LTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:37:59 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)) 01:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:37:59 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-VaznXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:37:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 01:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:37:59 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-MJPrRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:38:00 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)) 01:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:38:00 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-RjLh2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 01:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:38:00 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-3QU6Jt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:38:00 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)) 01:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:38:00 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-QQVWan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 01:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:38:00 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-eM8BZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:38:00 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)) 01:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:38:00 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-XJ025v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:38:00 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)) 01:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:38:00 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-FSipDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:38:00 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)) 01:38:00 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)) 01:38:00 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)) 01:38:00 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)) 01:38:00 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)) 01:38:00 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)) 01:38:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:38: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 01:38: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 01:38: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 01:38: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 01:38: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 01:38: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 01:38:03 INFO - Downloading blessings-1.6.tar.gz 01:38:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:38:03 INFO - Installing collected packages: blessings 01:38:03 INFO - Running setup.py install for blessings 01:38:03 INFO - Successfully installed blessings 01:38:03 INFO - Cleaning up... 01:38:03 INFO - Return code: 0 01:38:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:38:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:38:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:38:03 INFO - Reading from file tmpfile_stdout 01:38:03 INFO - Current package versions: 01:38:03 INFO - blessings == 1.6 01:38:03 INFO - blobuploader == 1.2.4 01:38:03 INFO - docopt == 0.6.1 01:38:03 INFO - manifestparser == 1.1 01:38:03 INFO - mozInstall == 1.12 01:38:03 INFO - mozcrash == 0.17 01:38:03 INFO - mozdebug == 0.1 01:38:03 INFO - mozdevice == 0.48 01:38:03 INFO - mozfile == 1.2 01:38:03 INFO - mozhttpd == 0.7 01:38:03 INFO - mozinfo == 0.9 01:38:03 INFO - mozleak == 0.1 01:38:03 INFO - mozlog == 3.1 01:38:03 INFO - moznetwork == 0.27 01:38:03 INFO - mozprocess == 0.22 01:38:03 INFO - mozprofile == 0.28 01:38:03 INFO - mozrunner == 6.11 01:38:03 INFO - mozscreenshot == 0.1 01:38:03 INFO - mozsystemmonitor == 0.0 01:38:03 INFO - moztest == 0.7 01:38:03 INFO - mozversion == 1.4 01:38:03 INFO - psutil == 3.1.1 01:38:03 INFO - requests == 1.2.3 01:38:03 INFO - wsgiref == 0.1.2 01:38:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:38:03 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')]} 01:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:03 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')]} 01:38:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:03 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 0x106515a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106525420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f38da2ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b4e00>, '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 0x10621ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063afb20>, '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.UniNK3SeP2/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.nKiiX8EZFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 01:38:03 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 01:38:03 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 01:38:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UniNK3SeP2/Render', 01:38:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:03 INFO - 'HOME': '/Users/cltbld', 01:38:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:03 INFO - 'LOGNAME': 'cltbld', 01:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:03 INFO - 'MOZ_NO_REMOTE': '1', 01:38:03 INFO - 'NO_EM_RESTART': '1', 01:38:03 INFO - 'PAGER': '/bin/cat', 01:38:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:03 INFO - 'PWD': '/builds/slave/test', 01:38:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:03 INFO - 'SHELL': '/bin/bash', 01:38:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nKiiX8EZFK/Listeners', 01:38:03 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 01:38:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:03 INFO - 'USER': 'cltbld', 01:38:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:03 INFO - 'XPC_FLAGS': '0x0', 01:38:03 INFO - 'XPC_SERVICE_NAME': '0', 01:38:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:03 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)) 01:38:03 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)) 01:38:03 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)) 01:38:03 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)) 01:38:03 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)) 01:38:03 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)) 01:38:03 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)) 01:38:03 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)) 01:38:03 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)) 01:38:03 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)) 01:38:03 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)) 01:38:03 INFO - Cleaning up... 01:38:03 INFO - Return code: 0 01:38:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:38:03 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')]} 01:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:03 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')]} 01:38:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:03 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 0x106515a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106525420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f38da2ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b4e00>, '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 0x10621ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063afb20>, '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.UniNK3SeP2/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.nKiiX8EZFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 01:38:03 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 01:38:03 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 01:38:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UniNK3SeP2/Render', 01:38:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:03 INFO - 'HOME': '/Users/cltbld', 01:38:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:03 INFO - 'LOGNAME': 'cltbld', 01:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:03 INFO - 'MOZ_NO_REMOTE': '1', 01:38:03 INFO - 'NO_EM_RESTART': '1', 01:38:03 INFO - 'PAGER': '/bin/cat', 01:38:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:03 INFO - 'PWD': '/builds/slave/test', 01:38:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:03 INFO - 'SHELL': '/bin/bash', 01:38:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nKiiX8EZFK/Listeners', 01:38:03 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 01:38:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:03 INFO - 'USER': 'cltbld', 01:38:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:03 INFO - 'XPC_FLAGS': '0x0', 01:38:03 INFO - 'XPC_SERVICE_NAME': '0', 01:38:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:04 INFO - Downloading/unpacking jsonschema 01:38: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 01:38: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 01:38: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 01:38: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 01:38: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 01:38: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 01:38:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:38:06 INFO - Downloading/unpacking functools32 (from jsonschema) 01:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:07 INFO - Downloading functools32-3.2.3-2.tar.gz 01:38:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:38:07 INFO - warning: no files found matching '*.txt' 01:38:07 INFO - no previously-included directories found matching 'build' 01:38:07 INFO - no previously-included directories found matching 'dist' 01:38:07 INFO - no previously-included directories found matching '.git*' 01:38:07 INFO - Installing collected packages: jsonschema, functools32 01:38:07 INFO - Running setup.py install for functools32 01:38:07 INFO - warning: no files found matching '*.txt' 01:38:07 INFO - no previously-included directories found matching 'build' 01:38:07 INFO - no previously-included directories found matching 'dist' 01:38:07 INFO - no previously-included directories found matching '.git*' 01:38:07 INFO - Successfully installed jsonschema functools32 01:38:07 INFO - Cleaning up... 01:38:07 INFO - Return code: 0 01:38:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:38:07 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')]} 01:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:07 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')]} 01:38:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:07 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106515a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106525420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f38da2ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b4e00>, '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 0x10621ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063afb20>, '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.UniNK3SeP2/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.nKiiX8EZFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 01:38:07 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', 'flake8'] in /builds/slave/test/build 01:38:07 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 flake8 01:38:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UniNK3SeP2/Render', 01:38:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:07 INFO - 'HOME': '/Users/cltbld', 01:38:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:07 INFO - 'LOGNAME': 'cltbld', 01:38:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:07 INFO - 'MOZ_NO_REMOTE': '1', 01:38:07 INFO - 'NO_EM_RESTART': '1', 01:38:07 INFO - 'PAGER': '/bin/cat', 01:38:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:07 INFO - 'PWD': '/builds/slave/test', 01:38:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:07 INFO - 'SHELL': '/bin/bash', 01:38:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nKiiX8EZFK/Listeners', 01:38:07 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 01:38:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:07 INFO - 'USER': 'cltbld', 01:38:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:07 INFO - 'XPC_FLAGS': '0x0', 01:38:07 INFO - 'XPC_SERVICE_NAME': '0', 01:38:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:07 INFO - Downloading/unpacking flake8 01:38:07 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 01:38:07 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 01:38:07 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 01:38:07 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 01:38:07 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 01:38:07 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 01:38:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:38:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:38:10 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 01:38:10 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 01:38:10 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 01:38:10 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 01:38:10 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 01:38:10 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 01:38:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:38:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:38:10 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 01:38:10 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 01:38:10 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 01:38:10 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 01:38:10 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 01:38:10 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 01:38:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:38:10 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 01:38:10 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 01:38:10 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 01:38:10 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 01:38:10 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 01:38:10 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 01:38:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:38:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:38:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:38:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:38:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:38:11 INFO - no previously-included directories found matching 'docs/_build' 01:38:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:38:11 INFO - Running setup.py install for pep8 01:38:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:38:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:38:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:38:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:38:11 INFO - no previously-included directories found matching 'docs/_build' 01:38:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:38:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:38:11 INFO - Cleaning up... 01:38:11 INFO - Return code: 0 01:38:11 INFO - Running post-action listener: _resource_record_post_action 01:38:11 INFO - Running post-action listener: _start_resource_monitoring 01:38:11 INFO - Starting resource monitoring. 01:38:11 INFO - ##### 01:38:11 INFO - ##### Running install step. 01:38:11 INFO - ##### 01:38:11 INFO - Running pre-action listener: _resource_record_pre_action 01:38:11 INFO - Running main action method: install 01:38:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:38:12 INFO - Reading from file tmpfile_stdout 01:38:12 INFO - Detecting whether we're running mozinstall >=1.0... 01:38:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:38:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:38:12 INFO - Reading from file tmpfile_stdout 01:38:12 INFO - Output received: 01:38:12 INFO - Usage: mozinstall [options] installer 01:38:12 INFO - Options: 01:38:12 INFO - -h, --help show this help message and exit 01:38:12 INFO - -d DEST, --destination=DEST 01:38:12 INFO - Directory to install application into. [default: 01:38:12 INFO - "/builds/slave/test"] 01:38:12 INFO - --app=APP Application being installed. [default: firefox] 01:38:12 INFO - mkdir: /builds/slave/test/build/application 01:38:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:38:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 01:38:33 INFO - Reading from file tmpfile_stdout 01:38:33 INFO - Output received: 01:38:33 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 01:38:33 INFO - Running post-action listener: _resource_record_post_action 01:38:33 INFO - ##### 01:38:33 INFO - ##### Running run-tests step. 01:38:33 INFO - ##### 01:38:33 INFO - Running pre-action listener: _resource_record_pre_action 01:38:33 INFO - Running main action method: run_tests 01:38:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:38:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:38:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:38:33 INFO - 2016-06-07 01:38:33.810 screenresolution[1642:7469] starting screenresolution argv=screenresolution get 01:38:33 INFO - 2016-06-07 01:38:33.812 screenresolution[1642:7469] Display 0: 1600x1200x32@60 01:38:33 INFO - 2016-06-07 01:38:33.818 screenresolution[1643:7472] starting screenresolution argv=screenresolution list 01:38:33 INFO - Available Modes on Display 0 01:38:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:38:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:38:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:38:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:38:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:38:34 INFO - Intel Iris: 01:38:34 INFO - Chipset Model: Intel Iris 01:38:34 INFO - Type: GPU 01:38:34 INFO - Bus: Built-In 01:38:34 INFO - VRAM (Dynamic, Max): 1536 MB 01:38:34 INFO - Vendor: Intel (0x8086) 01:38:34 INFO - Device ID: 0x0a2e 01:38:34 INFO - Revision ID: 0x0009 01:38:34 INFO - Displays: 01:38:34 INFO - Display: 01:38:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:38:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:38:34 INFO - Display Serial Number: 1600x1200 60 01:38:34 INFO - Main Display: Yes 01:38:34 INFO - Mirror: Off 01:38:34 INFO - Online: Yes 01:38:34 INFO - Rotation: Supported 01:38:34 INFO - Return code: 0 01:38:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:38:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:38:34 INFO - Python 2.7.3 01:38:34 INFO - Return code: 0 01:38:34 INFO - grabbing minidump binary from tooltool 01:38:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:38:34 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b4e00>, '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 0x10621ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063afb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:38:34 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 01:38:34 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 01:38:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:38:34 INFO - Return code: 0 01:38:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:38:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:38:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:38:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:38:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:38:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:38:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:38:35 INFO - Return code: 0 01:38:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--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 01:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0385 --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 01:38:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UniNK3SeP2/Render', 01:38:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:35 INFO - 'HOME': '/Users/cltbld', 01:38:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:35 INFO - 'LOGNAME': 'cltbld', 01:38:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:38:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:38:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:35 INFO - 'MOZ_NO_REMOTE': '1', 01:38:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:38:35 INFO - 'NO_EM_RESTART': '1', 01:38:35 INFO - 'PAGER': '/bin/cat', 01:38:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:35 INFO - 'PWD': '/builds/slave/test', 01:38:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:38:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:35 INFO - 'SHELL': '/bin/bash', 01:38:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nKiiX8EZFK/Listeners', 01:38:35 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 01:38:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:35 INFO - 'USER': 'cltbld', 01:38:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:35 INFO - 'XPC_FLAGS': '0x0', 01:38:35 INFO - 'XPC_SERVICE_NAME': '0', 01:38:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--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 01:38:35 INFO - mozversion application_buildid: 20160606120332 01:38:35 INFO - mozversion application_changeset: 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 01:38:35 INFO - mozversion application_display_name: Firefox 01:38:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:38:35 INFO - mozversion application_name: Firefox 01:38:35 INFO - mozversion application_remotingname: firefox 01:38:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:38:35 INFO - mozversion application_vendor: Mozilla 01:38:35 INFO - mozversion application_version: 48.0 01:38:35 INFO - mozversion platform_buildid: 20160606120332 01:38:35 INFO - mozversion platform_changeset: 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 01:38:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:38:35 INFO - mozversion platform_version: 48.0 01:38:35 INFO - using testdate: 1465288715 01:38:35 INFO - actual date: 1465288715 01:38:35 INFO - starting webserver on 'localhost:49278' 01:38:35 INFO - SUITE-START | Running 2 tests 01:38:35 INFO - TEST-START | dromaeo_css 01:38:35 INFO - operating with platform_type : mac_ 01:38:35 INFO - Initialising browser for dromaeo_css test... 01:38:35 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpJxjidP/profile http://localhost:49278/getInfo.html) 01:38:37 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 01:38:37 INFO - PROCESS | 1661 | colorDepth:24 01:38:37 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 01:38:37 INFO - PROCESS | 1661 | __metrics 01:38:37 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:38:37 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:38:38 INFO - TEST-INFO | 1661: exit 0 01:38:38 INFO - Browser initialized. 01:38:38 INFO - Running cycle 1/1 for dromaeo_css test... 01:38:38 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpJxjidP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:38:50 INFO - PROCESS | 1664 | RSS: Main: 161587200 01:38:50 INFO - PROCESS | 1664 | 01:39:41 INFO - PROCESS | 1664 | 1465288781150 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] 01:41:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 01:41:52 INFO - PROCESS | 1664 | RSS: Main: 254382080 01:41:52 INFO - PROCESS | 1664 | 01:44:55 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 01:44:55 INFO - PROCESS | 1664 | RSS: Main: 304283648 01:44:55 INFO - PROCESS | 1664 | 01:47:57 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 01:47:58 INFO - PROCESS | 1664 | RSS: Main: 309444608 01:47:58 INFO - PROCESS | 1664 | 01:51:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 01:51:00 INFO - PROCESS | 1664 | RSS: Main: 310865920 01:51:00 INFO - PROCESS | 1664 | 01:54:04 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 01:54:04 INFO - PROCESS | 1664 | RSS: Main: 276979712 01:54:04 INFO - PROCESS | 1664 | 01:57:07 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 01:57:07 INFO - PROCESS | 1664 | RSS: Main: 315035648 01:57:07 INFO - PROCESS | 1664 | 01:57:07 INFO - PROCESS | 1664 | __start_tp_report 01:57:07 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 01:57:07 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 01:57:07 INFO - PROCESS | 1664 | |i|pagename|runs| 01:57:07 INFO - PROCESS | 1664 | |0;dojo.html;2949.6755356910744;2968.985155074224;3446.6381029991867;3560.465930110484;3567.1973805893663;5759.4816466518005;5764.884702305957;5767.538596912247;5776.364599894014;5776.653400795955;5704.857378565527;5792.420757924206;5829.475347218749;5830.212921255629;5837.708114594264;4272.316429371302;4389.824407023715;5192.428832828386;5195.40252870919;5320.973395133019;5234.18870075139;5282.920756188661;5289.153735402337;5292.814751483699;5303;5211.687298762086;5277.973610131944;5278.656887302313;5292.417834038252;5318.547923426513;5262.526372626444;5321.467853214647;5331.600129990227;5333.386660534049;5356.062689029404;5121.129408000649;5145.819896303611;5162.92255616166;5258.579313654936;5268.4994925482015;5597.272354593877;5634.239377684001;5634.549236061105;5654.434556544354;5704.686242256675;6220.1602783624085;6243.531735119885;6244.406781355764;6244.781432649836;6264.812055638338;5395.622306438551;6054.122152287938;6153.4154255345675;6286.685665716742;6293.874122517524;5879.559033072536;5885;5901.17383566301;5913.172155898178;5913.408659134096;6343.175387199727;6357.936420635827;6360.52296077796;6364.109024736541;6373;6064.332923378425;6095.329513753483;6206.0690896365;6237.781677641261;6249.625022498664;6357.523185761086;6359.141515895388;6360.936390636127;6371.458426033839;6400.3599640036555;6224.066390041439;6283.434490895841;6289.811305660837;6292.402221788922;6311.0217916223055;6143.27988163315;6184.443400094013;6266.874662506724;6307.306196318401;6323.9051414228825;4939.506049395031;4968.950310496921;4986.102501549756;4994.600431965434;4994.750262486861;4488.079943611552;4494.752788596593;4496.887577810581;4507.188706032945;4538.115067561794;5651.773929042793;5736.397678243808;5765.596408251466;5857.384974577693;5896.262967129109;6298.118263443122;6350.206224221973;6354.110424540568;6359.268684101387;6371.044343348535;4855.344528488679;5791;5792.247007888947;5816.912746308809;5823.854403639943;5311.681299122065;5379.193121031876;5413.593980451482;5466.180072989084;5469.535089517435;5239.816606418757;6303.338149494421;6376.872462550816;6393.840153996187;6397.552171348054;6162;6378.394052564999;6394.552381333262;6412.1343618612755;6417;5339.946600533945;6328.9050664240995;6376.840578985562;6403.871922561616;6408.03879418091;6246.593971391845;6249.7500099997305;6251.218597675291;6257.7184026719815;6265.185525881606;5250.632455728215;5816;6124.319907527303;6254.28075771274;6368.267649220399;6118.314626315956;6186.721597528032;6194.256689197145;6196.3803619639475;6200.906986395298;6109.389061093855;6131.509479241739;6137.447629713347;6156.415140561639;6166.445019948226;4871.902561948812;5892.616979896293;5899.911501327398;6040.154378386941;6055.788047418319;6024.3674414187635;6057.091436284416;6117.57907269851;6147.415995480422;6189.381061893955;6060.937259363361;6075.605085669417;6085.421884920926;6093.6039157454625;6105.8168254952425 01:57:07 INFO - PROCESS | 1664 | |1;ext.html;527.6095689189999;555.7360253879408;601.0983524712927;606.2997238189889;635.3709827270998;32141.517877231832;39175.0206244844;39650.60349396505;39682.80158599203;39772;42827;43117.137657246916;43169.78415107928;43221;43517;9069.773255668628;9248.12142846432;9734.65928692496;9961.501924903761;9976.600935962553;11988.700282492964;12227.877721222763;12261.693457663541;12261.816072758871;12271;11913.046956243523;12000.279983201035;12017.459214335377;12027.458764355624;12028.398580070962;11862.288262704222;11947.163698541062;11957.641270761891;11960.820587691192;11962.880371196263;11562.190646654737;11675.883241167565;11687.590934317343;11689.41552922359;11706.063514918786;41775.74672759822;42218.36672449916;42244.78877605623;42313.94215144615;42385.36421953673;3301.8207982731283;3345.2640419107756;3413.76103672743;3461.0828130545424;3467.167879708877;25029.749702502922;28185;29654.258643533867;30866;30922.845385772926;2796.482650709605;2800.075974928269;2819.9577006344716;2847.3878116205115;2873.267316834215;442284;444262;444990;446083;446719;36905.81547092248;37430.812845935594;64409.35590644128;65946.67026664836;66156.33843661597;36058;36182.45726314108;36197;36212.91361259166;36225;13766.65583360404;16193.433229837136;16378.344866205456;16381.83618163827;16382.918085409736;12966.481340746453;13145.474181032843;13850.445982160802;14203.857961420461;14228.217448040457;8398.370122240734;8413.621387037598;8415;8479.406441549156;8495.702650407206;3504.9299014019575;3524;3524;3531.682148606638;3559.555055618048;940.3699521320732;1011.6459239266345;1058.5448257249457;1098.6594155811724;1112.1103117505963;30361.538880660595;30488.932887348838;30601.928932487703;30631.2342191447;30909.690903090683;970.6942313171328;1123.1632433836844;1193.570314686712;1227.2391117507202;1237.1216436330126;15323.310451029507;15521;15580.454684086002;15916.920415397848;16013.679726405406;612927;625812;626824;628750;630041;62134.067988980205;62662;62702.686486567276;62721.68639156775;62867;1398.8531204223614;1431.1985288238411;1536.9231538423035;1560.641052557918;1573.7482002879485;27977.020804271753;29131.85434072816;29492;29502.852485737432;29677.554836677034;46886;47257.7637111826;47291.763541182074;47362.289565655854;47492.762536187096;310081;315215.4239228789;315774;316537;317975;24315.878420607783;27119.45761084807;27188.592171117845;27638.44723105567;27707.722922770514;45607;46043;46861.76569117133;46994;47671.28493072676;36696.26607467782;37349.43975840419;38082.61917380791;38155.046123847125;39119;33796.986090417326;35579.644203558666;37270.44094338641;37644.43533347057;38248.426273605364;37142.25715485729;37592.2481550362;37622;37910.431343529315;37952;32188;33190;33276.1680957978;33335.49996750002;33406.66593334036;24707;24967.50064998726;25264.62103068492;25471.872640636677;25536.48927021412 01:57:07 INFO - PROCESS | 1664 | |2;jquery.html;375.9906002349941;423.616626952608;423.6335569732183;425.7062626787517;428.21850123524564;20471.795282047176;21332.253371132014;21352.35942921711;21557.67663485049;22611.321660350175;12380.319082450484;12643.241405515675;17362;21898.89050554749;21908;5193.80542475231;5319.547838433728;5803.158542011406;6419.64691941943;6443.742250309982;6322.114903913456;6323.715432805512;6354.618722876619;6395.424411802936;6399.744010239585;6089.45194932456;6322;6335.461485773715;6347.428731414172;6363.9045414318825;6227.065940108974;6279.937200628026;6304;6307.27466341372;6309.589876658002;5365.624406291561;5463.3717122530625;5562.666240025612;5569.972150139264;5582.91625625616;13986.510472133528;14003.089799163023;14177.291135443187;14234.288285585679;14241.572752817328;2149.9892500537553;2187.245400336897;2203.5923354179527;2236.2285011670947;2246.7416247131628;10056.899431005742;10121.746956326151;10196.49017549127;10490.790184196274;10523.000314970066;1846.8799528030636;1858.823411775879;1874.044237438916;1879.3798046644574;1880;116625.4168729151;118868.02818941459;120269;122691.77308226893;124479;11858.407079646156;12022.699432514257;12111.636650900487;12292.754144917051;12311.630651080302;15572.377104915677;15784.052956822627;15804.446844360622;15843.445479408165;15848.366065357488;7994.960025199837;8908.510031948175;9296.256299496024;9366.29752768545;9488.193503552136;9459.38513996588;9459.97280870115;9483.288753343526;9487.762805929908;9573.856392154123;4909.631777616693;4930.630202734809;4934.309196712463;4946;4973.079980203602;1777.3512667876391;2017.878927264369;2033.3188381892232;2066.90698918549;2090.5505316357057;888.391451855481;945.7115579748105;963.2246041936169;966.5698764050071;984.3601658921615;19773;20909.58180836405;21201.89399052995;21845.890770546044;22219.66670499944;869.7999460124207;1002.4486532407146;1005.7787286796893;1034.6016783538391;1079.6653037558383;10995.670129896116;11458.656240312805;11470.655880323604;11481.71295717597;11492.367919764492;622855;623896;634954;665911;684907;137038;137903;139436;140158;140213.59786402006;448.5872996842912;471.22718741264356;473.3136951420454;473.6684320975331;476.4711170600631;9791.46146961924;10813.188570971446;10898.836517452086;11080.224384293022;17918;50684.74657626688;50800.98398031945;51107.744461277456;52299.73850130725;53022.46977530175;70271.64864175646;71449.642751788;72486.91269631065;72555;73148;7137.714491420285;7903.446758726834;8393.790155246168;8512.616932237941;8594.011688655884;11309.491072901574;12833.807492887801;12999.740005199654;13226.933865330611;13277;11024.448777560929;11172.217944744116;11200.21598488098;11246.381449020382;11466.369349685847;10718.624848130277;10861.945690271497;11155.897277873435;11442.713932151762;11451.713707157387;11288.604898828502;11414.143939204427;11870.703232419259;11879;11927.165098443025;8387.66449342044;8390.244877961013;8596.35527335465;8619.482831030158;8944.329175311748;11363;11488;11520.251183673034;11662.55086431825;11840.881591183977 01:57:07 INFO - PROCESS | 1664 | |3;mootools.html;1459.7080583883226;1612.7661489084082;1616.5716085237411;1899.9860148242856;2024.0790440349642;8194.590270486475;8499.78750531237;8600.182982616658;8608.483490990537;8620.913790862089;8620.827583448327;8840.071792461795;8857.379983401148;8858.202761751458;8879.733607991771;2499.6500489931377;2501.5997440409537;2529.2665127113114;2533.138732830837;2533.2526904563106;2457.054034196838;2520.407704189514;2521.8865151068153;2525.053105085593;2527.5703130467864;2480.032787212979;2509.4479214572766;2528.3426309159568;2530.8861101250395;2533.7592928671747;2478.9008439662393;2529.8482091074593;2532.126416386353;2535.024015753948;2537.746225377466;2010.3164923925935;2509.498100379931;2518.962215566768;2519.6220566914926;2526.191618682004;8621.741347759576;8822.735317940473;8854.778630534223;8944.463332200025;8960.775980600472;5428.240046393468;5639.971800141014;5641.323041235037;5660.29246344207;5670.177824215507;3580.606133325332;3652.4703917932015;3694.9630503695157;3706.1475860552223;3733.7199710021855;1036.1451802263089;1048.8007278617038;1052.9684109476652;1062.8033813744482;1070.5236169904397;9732.513374331325;12156.088293378032;12162.02703783704;12193.756124877542;12194.512219511209;4610.538946105363;4682.742449165328;4705.317728929286;4773.331733557305;4793.18515852306;9489.857652135224;9495.762605934908;9500.619975200914;9503.28725345602;9543.950165481792;5438.320209973754;5490.835274941758;5492.588055895744;5499.532539734088;5503.834884953458;4566.840160594364;4739.218029025241;4868.561829435368;4978.925316120261;5081.618878584121;4100.569440208795;4159.226557490282;4212.85255016073;4239.385289133058;4253.510846252659;1355.593322003395;1376.7040086381276;1390.9165450073028;1401.7476854166143;1416.5679467762225;956.8803899512561;964.8504481805192;983.8966908474507;994.0606127209862;1003.7992401519725;5905.852353691193;5961.940380596226;6028.397160284024;6126.203593532812;6193.120199107437;477.2316570321779;482.1923278508484;487.096436111009;487.12804080695867;492.06016508469366;3922.254771593445;4014.297497937849;4043.332850079763;4114.033202197481;4151.605597468236;10545.261831671709;10978.231523793258;11005.229633925548;11010;11041.39272340013;9676.371035882787;10192.439415832201;10785.622503212508;10807;10838.241323107306;7943.880841787378;8273.958630206811;8320.251177393942;8498.447600905922;8550.358723095793;5912.61567998096;6022.638641681513;6028;6095.542834287535;6222.937770622236;9669.951650241705;9700;9705.320627556004;9860.901390986286;9866.802663946824;9627.277954153607;9982.35114717541;10051.798964020825;10088.646897358558;10120.443375614412;6962.373386395248;7153.2131465537705;7371.299726525972;7632.5038872473115;7751.108622508484;8279.917200828155;8321.625526851427;8349.081601024003;8374.623141958504;8389.538575378412;7243.492955493065;7334.596597187206;7373.594452305175;7455.105387353552;7481.476296659182;6251.6561589113035;7249.420046396381;7297.63511824396;7315.707371705285;7343.559386436831;7027.402670773042;7100.715971361088;7108.786736397709;7149.106361704787;7183.461240406886;4894.314795928502;4962.503749625153;5019.272205530105;5036.974815125901;5133.332666753298;5129.923051154161;5133.512316329943;5172.379314482287;5206.245094461357;5425.294711687613 01:57:07 INFO - PROCESS | 1664 | |4;prototype.html;332.54441415261095;338.4309288807396;344.3973047167457;347.70097715964283;349.9265154317593;6478.935210647892;6664.833379165517;6682.498812589057;6684.933150668492;6696.4642828573715;6436.292007879129;6565.113709649196;6594.241662208848;6613.239477460093;6654.500912431563;4359.2371335016405;4734.550217729327;4977.402711674604;4998.775055122528;5011.223260394645;3797.7087790151345;4394.934075988846;4780.641451891105;5024.572911302544;5024.773885175176;4794.592459640935;4979.751012449381;5001.499850014988;5032.220005899091;5050.823221187259;4972.2044472884345;4973.900521989576;4981.950180498185;4983.401991760976;4985.576226020793;4245.681573881971;4248.978755106235;4264.552222016675;4267.786610669454;4274.935875961863;6353.110564520971;6389.041643753428;6402.295747467775;6402.807915762534;6433.581817181868;5300.840974770763;5327.573794096463;5632.943670563324;5828.417158284137;5833.854153646193;5614.719264036823;6521.902171467432;7029.258589150236;7104.360607545294;7144.46416518758;4257.723247988902;4785.760711964388;4786.808527658889;4790.353302304214;4790.736509491977;7520.285572870624;7520.774376768706;7535.510191837513;7536.660850261751;7561.470697051209;4856.028794241166;4862.222044472867;4867.0995865764235;4874.366332376769;4880.267959806058;4591.9540804591525;4741.075490279362;4929.531694489017;4956.628252881048;4960.826371077067;5634.126710359818;5640.182173584807;5646.322441307069;5652.519535839499;5707.143928410772;5432.994895944263;6764.661766911733;7105.573665580082;7115.466340024519;7119.074520312326;1569.3173469540786;1602.166873225916;1605.6226786705115;1610.6698126883962;1630.8124565674862;856.9914300857037;1063.2450959818616;1064.8243039898484;1066.7706443114769;1069.5668254356997;5257.973710131425;6622.337766223339;6733.8653226935185;6744.5616034957575;6760.3239676032;5905.852353691193;7403.185649578542;7418;7421.4804963653105;7429.4799364044;5453.945460545423;5480.534154596904;6706.865862682719;6716.764913228014;6732.697028633625;3837.808109594509;3862.1503269280715;3912.980435097806;3935.763854168759;3960.8415663373144;6475.967620161869;7641.083070031632;7764.572948487775;7768.961155194188;7886;7752.030996125484;7752.8449431012195;7758.650860711282;7781.844363112706;7782.377409807202;6294.276158241769;6319.8104056878365;7846.843063138819;7875.684972601031;7876.094249161305;7258.346748792634;7526.586037767756;7537.886931696139;7551.773446796605;7568.5458872467825;7321.524100933422;7353.558786472829;7388.039554857834;7390.9260907390235;7396.704131834882;7837.41219408558;7842.64708088126;7847.176046515034;7863.724769633035;7871.48835325702;4209.34755112958;4814.037192561432;4909.803607855646;4943.406791185081;4943.8516844494725;5932.406759324033;7038.577685338896;7370.742024029133;7375.963120184365;7420.109586849397;7045.436365090883;7059;7063.858722825412;7068.222495525591;7103.609301488261;7101.2898710128575;7103.431725462054;7117.3238542338395;7123.323284287984;7124.073870397023;6750.291860205328;6962.860742785015;6966.9651651741415;6973.267806880204;6990.370866622028;6930;6991.2659170788365;7116.8932466014085;7134;7147.571145731273;6876;6964.930350696428;6979.965100174467;6995.615241161785;7010.4041156500225 01:57:07 INFO - PROCESS | 1664 | |5;yui.html;608.6196127420361;691.9169699636044;747.2378174262254;754.4982586579924;798.9760307190783;15393.153376564285;15550.92224538877;15746.370145194207;15759.921200393996;15790.289436975336;14508.274586270698;14569.854301456982;14599.562013139595;14611;14612.050216735905;789.2186735132207;803.7789607857857;954.766082309835;974.3325821812045;998.4907697074508;992.5235886774352;993.1111655068709;1000.8498725191207;1002.003007008028;1004.1013293102669;931.5947562810165;1056.587930707025;1060.7189094889861;1063.016709543673;1063.191974099682;835.4945258118842;844.4975239732349;848.5799529233008;850.3197442046369;1054.4464156317956;761.466973118819;773.5552057566922;774.7017398301695;775.441681988967;775.7789030126444;15596.064236145867;15700.685986280325;15708;15717.056976581442;15755.44855930037;12886.935565322206;12900.09699321048;13131.934340328333;13197;13226.537071202461;10219.744506387326;10267.43529105899;10268.640597579124;10337.328073675262;10811.18916081297;3586.444101164324;3595.496630471736;3644;3676.9632303677154;3864.768113913174;83757.58121209416;84496;84946.15053849445;85033.14966850315;88014.5599272006;9787.895801141744;9848.162906152915;9850.064243896819;9853.605855765833;10116.54475548602;22747;22759.88620056889;22774.20290289832;22813.85930703458;22818.885905570365;6877.552959057645;7151.533935543202;7496.7376141834775;7518.060242469692;7521.849563008709;4184.079502509443;4241;4318.697691161588;4320.481542214954;4519.5480451954545;3738;3796.905077373088;3807.9238415232094;3808.8286027128847;3813.523309586302;655.5673255651247;670.969806358715;671.452765995712;681.5501768832547;697.3375293471223;617.5121653893464;619.5074915442234;628.8302158417201;629.1883470323323;635.4154178156108;8097.878531822027;8314.542700151549;8556.572171391406;8707.869381959275;8886.555672216364;598.1058317814808;620.1225266248298;624.8031869960947;626.3754867106808;627.109504503606;3820.7898565578603;4021.9798901005306;4046.1705350403095;4100.530421840149;4103.138340948434;80538;95340;97894.53158202632;99509.5073573897;99598.5020074895;78310.21689783143;80419;80531;80806.59596702097;81053;69935.95096073563;71665.20836979117;71682;72469;72810.63594681992;7981.960090199512;8046.718364857202;8088.110307866013;8180.550069745983;8277.089520152898;49874;50138.74930625324;50290.74273143201;50301.74849125731;50355.24467133069;64548.386290343115;64868;65115;65169.67415163083;66258.33741662714;10003.949980250052;11037.172212083966;11270.605528806453;11337.093032557541;11648.650540483799;11988.64034078979;12110.515579376726;12115.878841211475;12198.14612977083;12728.427220774902;9496.47769372691;10793.003060538891;11577.421128943352;11784.234024788362;11933.045356371296;11694.239874408137;11870.169088163746;11885.22746021506;12181;12266.632001039983;11632.360220187971;11739.882601173878;11868.881311186778;11874.406279685809;12077.335746533674;9927.453990030328;10151.847722284025;10166.339187952759;10465.9476702616;10572.259941804308;10023.298369114093;10207.897921020694;10237.539310731185;10244.282900197213;10287.43419111926 01:57:07 INFO - PROCESS | 1664 | __end_tp_report 01:57:07 INFO - PROCESS | 1664 | __start_cc_report 01:57:07 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1104 01:57:07 INFO - PROCESS | 1664 | __end_cc_report 01:57:07 INFO - PROCESS | 1664 | __startTimestamp1465289827514__endTimestamp 01:57:07 INFO - PROCESS | 1664 | 01:57:07 INFO - PROCESS | 1664 | ------- Summary: start ------- 01:57:07 INFO - PROCESS | 1664 | Number of tests: 6 01:57:07 INFO - PROCESS | 1664 | 01:57:07 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5752.70 Median:5913.41 stddev:677.82 (11.5%) stddev-sans-first:643.24 01:57:07 INFO - PROCESS | 1664 | Values: 2949.7 2969.0 3446.6 3560.5 3567.2 5759.5 5764.9 5767.5 5776.4 5776.7 5704.9 5792.4 5829.5 5830.2 5837.7 4272.3 4389.8 5192.4 5195.4 5321.0 5234.2 5282.9 5289.2 5292.8 5303.0 5211.7 5278.0 5278.7 5292.4 5318.5 5262.5 5321.5 5331.6 5333.4 5356.1 5121.1 5145.8 5162.9 5258.6 5268.5 5597.3 5634.2 5634.5 5654.4 5704.7 6220.2 6243.5 6244.4 6244.8 6264.8 5395.6 6054.1 6153.4 6286.7 6293.9 5879.6 5885.0 5901.2 5913.2 5913.4 6343.2 6357.9 6360.5 6364.1 6373.0 6064.3 6095.3 6206.1 6237.8 6249.6 6357.5 6359.1 6360.9 6371.5 6400.4 6224.1 6283.4 6289.8 6292.4 6311.0 6143.3 6184.4 6266.9 6307.3 6323.9 4939.5 4969.0 4986.1 4994.6 4994.8 4488.1 4494.8 4496.9 4507.2 4538.1 5651.8 5736.4 5765.6 5857.4 5896.3 6298.1 6350.2 6354.1 6359.3 6371.0 4855.3 5791.0 5792.2 5816.9 5823.9 5311.7 5379.2 5413.6 5466.2 5469.5 5239.8 6303.3 6376.9 6393.8 6397.6 6162.0 6378.4 6394.6 6412.1 6417.0 5339.9 6328.9 6376.8 6403.9 6408.0 6246.6 6249.8 6251.2 6257.7 6265.2 5250.6 5816.0 6124.3 6254.3 6368.3 6118.3 6186.7 6194.3 6196.4 6200.9 6109.4 6131.5 6137.4 6156.4 6166.4 4871.9 5892.6 5899.9 6040.2 6055.8 6024.4 6057.1 6117.6 6147.4 6189.4 6060.9 6075.6 6085.4 6093.6 6105.8 01:57:07 INFO - PROCESS | 1664 | 01:57:07 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:60166.11 Median:18571.13 stddev:127939.01 (688.9%) stddev-sans-first:128219.55 01:57:07 INFO - PROCESS | 1664 | Values: 527.6 555.7 601.1 606.3 635.4 32141.5 39175.0 39650.6 39682.8 39772.0 42827.0 43117.1 43169.8 43221.0 43517.0 9069.8 9248.1 9734.7 9961.5 9976.6 11988.7 12227.9 12261.7 12261.8 12271.0 11913.0 12000.3 12017.5 12027.5 12028.4 11862.3 11947.2 11957.6 11960.8 11962.9 11562.2 11675.9 11687.6 11689.4 11706.1 41775.7 42218.4 42244.8 42313.9 42385.4 3301.8 3345.3 3413.8 3461.1 3467.2 25029.7 28185.0 29654.3 30866.0 30922.8 2796.5 2800.1 2820.0 2847.4 2873.3 442284.0 444262.0 444990.0 446083.0 446719.0 36905.8 37430.8 64409.4 65946.7 66156.3 36058.0 36182.5 36197.0 36212.9 36225.0 13766.7 16193.4 16378.3 16381.8 16382.9 12966.5 13145.5 13850.4 14203.9 14228.2 8398.4 8413.6 8415.0 8479.4 8495.7 3504.9 3524.0 3524.0 3531.7 3559.6 940.4 1011.6 1058.5 1098.7 1112.1 30361.5 30488.9 30601.9 30631.2 30909.7 970.7 1123.2 1193.6 1227.2 1237.1 15323.3 15521.0 15580.5 15916.9 16013.7 612927.0 625812.0 626824.0 628750.0 630041.0 62134.1 62662.0 62702.7 62721.7 62867.0 1398.9 1431.2 1536.9 1560.6 1573.7 27977.0 29131.9 29492.0 29502.9 29677.6 46886.0 47257.8 47291.8 47362.3 47492.8 310081.0 315215.4 315774.0 316537.0 317975.0 24315.9 27119.5 27188.6 27638.4 27707.7 45607.0 46043.0 46861.8 46994.0 47671.3 36696.3 37349.4 38082.6 38155.0 39119.0 33797.0 35579.6 37270.4 37644.4 38248.4 37142.3 37592.2 37622.0 37910.4 37952.0 32188.0 33190.0 33276.2 33335.5 33406.7 24707.0 24967.5 25264.6 25471.9 25536.5 01:57:07 INFO - PROCESS | 1664 | 01:57:07 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:36192.40 Median:12043.07 stddev:107868.33 (895.7%) stddev-sans-first:108137.40 01:57:07 INFO - PROCESS | 1664 | Values: 376.0 423.6 423.6 425.7 428.2 20471.8 21332.3 21352.4 21557.7 22611.3 12380.3 12643.2 17362.0 21898.9 21908.0 5193.8 5319.5 5803.2 6419.6 6443.7 6322.1 6323.7 6354.6 6395.4 6399.7 6089.5 6322.0 6335.5 6347.4 6363.9 6227.1 6279.9 6304.0 6307.3 6309.6 5365.6 5463.4 5562.7 5570.0 5582.9 13986.5 14003.1 14177.3 14234.3 14241.6 2150.0 2187.2 2203.6 2236.2 2246.7 10056.9 10121.7 10196.5 10490.8 10523.0 1846.9 1858.8 1874.0 1879.4 1880.0 116625.4 118868.0 120269.0 122691.8 124479.0 11858.4 12022.7 12111.6 12292.8 12311.6 15572.4 15784.1 15804.4 15843.4 15848.4 7995.0 8908.5 9296.3 9366.3 9488.2 9459.4 9460.0 9483.3 9487.8 9573.9 4909.6 4930.6 4934.3 4946.0 4973.1 1777.4 2017.9 2033.3 2066.9 2090.6 888.4 945.7 963.2 966.6 984.4 19773.0 20909.6 21201.9 21845.9 22219.7 869.8 1002.4 1005.8 1034.6 1079.7 10995.7 11458.7 11470.7 11481.7 11492.4 622855.0 623896.0 634954.0 665911.0 684907.0 137038.0 137903.0 139436.0 140158.0 140213.6 448.6 471.2 473.3 473.7 476.5 9791.5 10813.2 10898.8 11080.2 17918.0 50684.7 50801.0 51107.7 52299.7 53022.5 70271.6 71449.6 72486.9 72555.0 73148.0 7137.7 7903.4 8393.8 8512.6 8594.0 11309.5 12833.8 12999.7 13226.9 13277.0 11024.4 11172.2 11200.2 11246.4 11466.4 10718.6 10861.9 11155.9 11442.7 11451.7 11288.6 11414.1 11870.7 11879.0 11927.2 8387.7 8390.2 8596.4 8619.5 8944.3 11363.0 11488.0 11520.3 11662.6 11840.9 01:57:07 INFO - PROCESS | 1664 | 01:57:07 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5752.43 Median:5105.77 stddev:3153.60 (61.8%) stddev-sans-first:3145.95 01:57:07 INFO - PROCESS | 1664 | Values: 1459.7 1612.8 1616.6 1900.0 2024.1 8194.6 8499.8 8600.2 8608.5 8620.9 8620.8 8840.1 8857.4 8858.2 8879.7 2499.7 2501.6 2529.3 2533.1 2533.3 2457.1 2520.4 2521.9 2525.1 2527.6 2480.0 2509.4 2528.3 2530.9 2533.8 2478.9 2529.8 2532.1 2535.0 2537.7 2010.3 2509.5 2519.0 2519.6 2526.2 8621.7 8822.7 8854.8 8944.5 8960.8 5428.2 5640.0 5641.3 5660.3 5670.2 3580.6 3652.5 3695.0 3706.1 3733.7 1036.1 1048.8 1053.0 1062.8 1070.5 9732.5 12156.1 12162.0 12193.8 12194.5 4610.5 4682.7 4705.3 4773.3 4793.2 9489.9 9495.8 9500.6 9503.3 9544.0 5438.3 5490.8 5492.6 5499.5 5503.8 4566.8 4739.2 4868.6 4978.9 5081.6 4100.6 4159.2 4212.9 4239.4 4253.5 1355.6 1376.7 1390.9 1401.7 1416.6 956.9 964.9 983.9 994.1 1003.8 5905.9 5961.9 6028.4 6126.2 6193.1 477.2 482.2 487.1 487.1 492.1 3922.3 4014.3 4043.3 4114.0 4151.6 10545.3 10978.2 11005.2 11010.0 11041.4 9676.4 10192.4 10785.6 10807.0 10838.2 7943.9 8274.0 8320.3 8498.4 8550.4 5912.6 6022.6 6028.0 6095.5 6222.9 9670.0 9700.0 9705.3 9860.9 9866.8 9627.3 9982.4 10051.8 10088.6 10120.4 6962.4 7153.2 7371.3 7632.5 7751.1 8279.9 8321.6 8349.1 8374.6 8389.5 7243.5 7334.6 7373.6 7455.1 7481.5 6251.7 7249.4 7297.6 7315.7 7343.6 7027.4 7100.7 7108.8 7149.1 7183.5 4894.3 4962.5 5019.3 5037.0 5133.3 5129.9 5133.5 5172.4 5206.2 5425.3 01:57:07 INFO - PROCESS | 1664 | 01:57:07 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:5793.99 Median:6500.42 stddev:1858.88 (28.6%) stddev-sans-first:1818.34 01:57:07 INFO - PROCESS | 1664 | Values: 332.5 338.4 344.4 347.7 349.9 6478.9 6664.8 6682.5 6684.9 6696.5 6436.3 6565.1 6594.2 6613.2 6654.5 4359.2 4734.6 4977.4 4998.8 5011.2 3797.7 4394.9 4780.6 5024.6 5024.8 4794.6 4979.8 5001.5 5032.2 5050.8 4972.2 4973.9 4982.0 4983.4 4985.6 4245.7 4249.0 4264.6 4267.8 4274.9 6353.1 6389.0 6402.3 6402.8 6433.6 5300.8 5327.6 5632.9 5828.4 5833.9 5614.7 6521.9 7029.3 7104.4 7144.5 4257.7 4785.8 4786.8 4790.4 4790.7 7520.3 7520.8 7535.5 7536.7 7561.5 4856.0 4862.2 4867.1 4874.4 4880.3 4592.0 4741.1 4929.5 4956.6 4960.8 5634.1 5640.2 5646.3 5652.5 5707.1 5433.0 6764.7 7105.6 7115.5 7119.1 1569.3 1602.2 1605.6 1610.7 1630.8 857.0 1063.2 1064.8 1066.8 1069.6 5258.0 6622.3 6733.9 6744.6 6760.3 5905.9 7403.2 7418.0 7421.5 7429.5 5453.9 5480.5 6706.9 6716.8 6732.7 3837.8 3862.2 3913.0 3935.8 3960.8 6476.0 7641.1 7764.6 7769.0 7886.0 7752.0 7752.8 7758.7 7781.8 7782.4 6294.3 6319.8 7846.8 7875.7 7876.1 7258.3 7526.6 7537.9 7551.8 7568.5 7321.5 7353.6 7388.0 7390.9 7396.7 7837.4 7842.6 7847.2 7863.7 7871.5 4209.3 4814.0 4909.8 4943.4 4943.9 5932.4 7038.6 7370.7 7376.0 7420.1 7045.4 7059.0 7063.9 7068.2 7103.6 7101.3 7103.4 7117.3 7123.3 7124.1 6750.3 6962.9 6967.0 6973.3 6990.4 6930.0 6991.3 7116.9 7134.0 7147.6 6876.0 6964.9 6980.0 6995.6 7010.4 01:57:07 INFO - PROCESS | 1664 | 01:57:07 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18745.91 Median:4420.01 stddev:26274.74 (594.4%) stddev-sans-first:26313.15 01:57:07 INFO - PROCESS | 1664 | Values: 608.6 691.9 747.2 754.5 799.0 15393.2 15550.9 15746.4 15759.9 15790.3 14508.3 14569.9 14599.6 14611.0 14612.1 789.2 803.8 954.8 974.3 998.5 992.5 993.1 1000.8 1002.0 1004.1 931.6 1056.6 1060.7 1063.0 1063.2 835.5 844.5 848.6 850.3 1054.4 761.5 773.6 774.7 775.4 775.8 15596.1 15700.7 15708.0 15717.1 15755.4 12886.9 12900.1 13131.9 13197.0 13226.5 10219.7 10267.4 10268.6 10337.3 10811.2 3586.4 3595.5 3644.0 3677.0 3864.8 83757.6 84496.0 84946.2 85033.1 88014.6 9787.9 9848.2 9850.1 9853.6 10116.5 22747.0 22759.9 22774.2 22813.9 22818.9 6877.6 7151.5 7496.7 7518.1 7521.8 4184.1 4241.0 4318.7 4320.5 4519.5 3738.0 3796.9 3807.9 3808.8 3813.5 655.6 671.0 671.5 681.6 697.3 617.5 619.5 628.8 629.2 635.4 8097.9 8314.5 8556.6 8707.9 8886.6 598.1 620.1 624.8 626.4 627.1 3820.8 4022.0 4046.2 4100.5 4103.1 80538.0 95340.0 97894.5 99509.5 99598.5 78310.2 80419.0 80531.0 80806.6 81053.0 69936.0 71665.2 71682.0 72469.0 72810.6 7982.0 8046.7 8088.1 8180.6 8277.1 49874.0 50138.7 50290.7 50301.7 50355.2 64548.4 64868.0 65115.0 65169.7 66258.3 10003.9 11037.2 11270.6 11337.1 11648.7 11988.6 12110.5 12115.9 12198.1 12728.4 9496.5 10793.0 11577.4 11784.2 11933.0 11694.2 11870.2 11885.2 12181.0 12266.6 11632.4 11739.9 11868.9 11874.4 12077.3 9927.5 10151.8 10166.3 10465.9 10572.3 10023.3 10207.9 10237.5 10244.3 10287.4 01:57:07 INFO - PROCESS | 1664 | -------- Summary: end -------- 01:57:07 INFO - PROCESS | 1664 | 01:57:08 INFO - TEST-INFO | 1664: exit 0 01:57:08 INFO - TEST-OK | dromaeo_css | took 1112581ms 01:57:08 INFO - TEST-START | kraken 01:57:08 INFO - operating with platform_type : mac_ 01:57:08 INFO - Initialising browser for kraken test... 01:57:08 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpMZoQ0B/profile http://localhost:49278/getInfo.html) 01:57:10 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 01:57:10 INFO - PROCESS | 1673 | colorDepth:24 01:57:10 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 01:57:10 INFO - PROCESS | 1673 | __metrics 01:57:10 INFO - PROCESS | 1673 | 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" 01:57:10 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:57:10 INFO - TEST-INFO | 1673: exit 0 01:57:10 INFO - Browser initialized. 01:57:10 INFO - Running cycle 1/1 for kraken test... 01:57:10 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpMZoQ0B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:57:22 INFO - PROCESS | 1676 | RSS: Main: 161411072 01:57:22 INFO - PROCESS | 1676 | 01:57:25 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 01:57:26 INFO - PROCESS | 1676 | RSS: Main: 223936512 01:57:26 INFO - PROCESS | 1676 | 01:57:28 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 01:57:29 INFO - PROCESS | 1676 | RSS: Main: 239775744 01:57:29 INFO - PROCESS | 1676 | 01:57:32 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 01:57:33 INFO - PROCESS | 1676 | RSS: Main: 326582272 01:57:33 INFO - PROCESS | 1676 | 01:57:35 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 01:57:35 INFO - PROCESS | 1676 | RSS: Main: 230375424 01:57:35 INFO - PROCESS | 1676 | 01:57:37 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:57:38 INFO - PROCESS | 1676 | RSS: Main: 230649856 01:57:38 INFO - PROCESS | 1676 | 01:57:41 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 01:57:41 INFO - PROCESS | 1676 | RSS: Main: 270438400 01:57:41 INFO - PROCESS | 1676 | 01:57:44 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 01:57:45 INFO - PROCESS | 1676 | RSS: Main: 270774272 01:57:45 INFO - PROCESS | 1676 | 01:57:47 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 01:57:48 INFO - PROCESS | 1676 | RSS: Main: 270016512 01:57:48 INFO - PROCESS | 1676 | 01:57:50 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:57:50 INFO - PROCESS | 1676 | RSS: Main: 260608000 01:57:50 INFO - PROCESS | 1676 | 01:57:52 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:57:53 INFO - PROCESS | 1676 | RSS: Main: 266817536 01:57:53 INFO - PROCESS | 1676 | 01:57:55 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:57:55 INFO - PROCESS | 1676 | RSS: Main: 270770176 01:57:55 INFO - PROCESS | 1676 | 01:57:58 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:57:59 INFO - PROCESS | 1676 | RSS: Main: 277778432 01:57:59 INFO - PROCESS | 1676 | 01:58:01 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:58:02 INFO - PROCESS | 1676 | RSS: Main: 274984960 01:58:02 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 01:58:04 INFO - PROCESS | 1676 | RSS: Main: 336568320 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | __start_tp_report 01:58:04 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 01:58:04 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 01:58:04 INFO - PROCESS | 1676 | |i|pagename|runs| 01:58:04 INFO - PROCESS | 1676 | |0;ai-astar;114;114;117;113;113;113;113;113;114;134 01:58:04 INFO - PROCESS | 1676 | |1;audio-beat-detection;155;122;142;162;117;142;116;154;153;117 01:58:04 INFO - PROCESS | 1676 | |2;audio-dft;186;184;184;183;189;185;183;184;185;184 01:58:04 INFO - PROCESS | 1676 | |3;audio-fft;69;78;72;153;57;72;81;93;150;55 01:58:04 INFO - PROCESS | 1676 | |4;audio-oscillator;92;90;139;76;85;85;120;79;76;121 01:58:04 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;87;84;88;85;87;85;89;84;88;84 01:58:04 INFO - PROCESS | 1676 | |6;imaging-darkroom;92;92;93;93;92;92;92;93;92;93 01:58:04 INFO - PROCESS | 1676 | |7;imaging-desaturate;86;86;86;84;85;86;86;86;82;84 01:58:04 INFO - PROCESS | 1676 | |8;json-parse-financial;57;62;61;61;60;62;63;79;56;55 01:58:04 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;62;60;69;113;105;68;65;66;65;65 01:58:04 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;80;80;80;79;78;77;80;79;79;84 01:58:04 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;128;129;135;123;108;136;139;139;141;126 01:58:04 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;161;160;156;163;163;160;157;163;163;162 01:58:04 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;71;83;92;90;88;68;65;67;67;66 01:58:04 INFO - PROCESS | 1676 | __end_tp_report 01:58:04 INFO - PROCESS | 1676 | __start_cc_report 01:58:04 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1853 01:58:04 INFO - PROCESS | 1676 | __end_cc_report 01:58:04 INFO - PROCESS | 1676 | __startTimestamp1465289884654__endTimestamp 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | ------- Summary: start ------- 01:58:04 INFO - PROCESS | 1676 | Number of tests: 14 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:115.80 Median:114.00 stddev:6.51 (5.7%) stddev-sans-first:6.87 01:58:04 INFO - PROCESS | 1676 | Values: 114.0 114.0 117.0 113.0 113.0 113.0 113.0 113.0 114.0 134.0 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:147.50 stddev:18.26 (12.4%) stddev-sans-first:18.30 01:58:04 INFO - PROCESS | 1676 | Values: 155.0 122.0 142.0 162.0 117.0 142.0 116.0 154.0 153.0 117.0 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:184.70 Median:184.50 stddev:1.77 (1.0%) stddev-sans-first:1.81 01:58:04 INFO - PROCESS | 1676 | Values: 186.0 184.0 184.0 183.0 189.0 185.0 183.0 184.0 185.0 184.0 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:88.00 Median:72.00 stddev:35.22 (48.9%) stddev-sans-first:36.68 01:58:04 INFO - PROCESS | 1676 | Values: 69.0 78.0 72.0 153.0 57.0 72.0 81.0 93.0 150.0 55.0 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:96.30 Median:82.00 stddev:22.19 (27.1%) stddev-sans-first:23.48 01:58:04 INFO - PROCESS | 1676 | Values: 92.0 90.0 139.0 76.0 85.0 85.0 120.0 79.0 76.0 121.0 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.00 stddev:1.91 (2.2%) stddev-sans-first:2.00 01:58:04 INFO - PROCESS | 1676 | Values: 87.0 84.0 88.0 85.0 87.0 85.0 89.0 84.0 88.0 84.0 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:92.40 Median:92.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 01:58:04 INFO - PROCESS | 1676 | Values: 92.0 92.0 93.0 93.0 92.0 92.0 92.0 93.0 92.0 93.0 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:86.00 stddev:1.37 (1.6%) stddev-sans-first:1.41 01:58:04 INFO - PROCESS | 1676 | Values: 86.0 86.0 86.0 84.0 85.0 86.0 86.0 86.0 82.0 84.0 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:61.60 Median:61.50 stddev:6.70 (10.9%) stddev-sans-first:6.90 01:58:04 INFO - PROCESS | 1676 | Values: 57.0 62.0 61.0 61.0 60.0 62.0 63.0 79.0 56.0 55.0 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:73.80 Median:65.00 stddev:18.83 (29.0%) stddev-sans-first:19.48 01:58:04 INFO - PROCESS | 1676 | Values: 62.0 60.0 69.0 113.0 105.0 68.0 65.0 66.0 65.0 65.0 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:80.00 stddev:1.84 (2.3%) stddev-sans-first:1.94 01:58:04 INFO - PROCESS | 1676 | Values: 80.0 80.0 80.0 79.0 78.0 77.0 80.0 79.0 79.0 84.0 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:130.40 Median:135.50 stddev:9.98 (7.4%) stddev-sans-first:10.55 01:58:04 INFO - PROCESS | 1676 | Values: 128.0 129.0 135.0 123.0 108.0 136.0 139.0 139.0 141.0 126.0 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.80 Median:162.50 stddev:2.57 (1.6%) stddev-sans-first:2.73 01:58:04 INFO - PROCESS | 1676 | Values: 161.0 160.0 156.0 163.0 163.0 160.0 157.0 163.0 163.0 162.0 01:58:04 INFO - PROCESS | 1676 | 01:58:04 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.70 Median:77.00 stddev:11.14 (14.5%) stddev-sans-first:11.68 01:58:04 INFO - PROCESS | 1676 | Values: 71.0 83.0 92.0 90.0 88.0 68.0 65.0 67.0 67.0 66.0 01:58:04 INFO - PROCESS | 1676 | -------- Summary: end -------- 01:58:04 INFO - PROCESS | 1676 | 01:58:05 INFO - TEST-INFO | 1676: exit 0 01:58:05 INFO - TEST-OK | kraken | took 57376ms 01:58:05 INFO - SUITE-END | took 1169s 01:58:05 INFO - Completed test suite (00:19:30) 01:58:05 INFO - javascript benchmark 01:58:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2949.6755356910744, 2968.985155074224, 3446.6381029991867, 3560.465930110484, 3567.1973805893663, 5759.4816466518005, 5764.884702305957, 5767.538596912247, 5776.364599894014, 5776.653400795955, 5704.857378565527, 5792.420757924206, 5829.475347218749, 5830.212921255629, 5837.708114594264, 4272.316429371302, 4389.824407023715, 5192.428832828386, 5195.40252870919, 5320.973395133019, 5234.18870075139, 5282.920756188661, 5289.153735402337, 5292.814751483699, 5303.0, 5211.687298762086, 5277.973610131944, 5278.656887302313, 5292.417834038252, 5318.547923426513, 5262.526372626444, 5321.467853214647, 5331.600129990227, 5333.386660534049, 5356.062689029404, 5121.129408000649, 5145.819896303611, 5162.92255616166, 5258.579313654936, 5268.4994925482015, 5597.272354593877, 5634.239377684001, 5634.549236061105, 5654.434556544354, 5704.686242256675, 6220.1602783624085, 6243.531735119885, 6244.406781355764, 6244.781432649836, 6264.812055638338, 5395.622306438551, 6054.122152287938, 6153.4154255345675, 6286.685665716742, 6293.874122517524, 5879.559033072536, 5885.0, 5901.17383566301, 5913.172155898178, 5913.408659134096, 6343.175387199727, 6357.936420635827, 6360.52296077796, 6364.109024736541, 6373.0, 6064.332923378425, 6095.329513753483, 6206.0690896365, 6237.781677641261, 6249.625022498664, 6357.523185761086, 6359.141515895388, 6360.936390636127, 6371.458426033839, 6400.3599640036555, 6224.066390041439, 6283.434490895841, 6289.811305660837, 6292.402221788922, 6311.0217916223055, 6143.27988163315, 6184.443400094013, 6266.874662506724, 6307.306196318401, 6323.9051414228825, 4939.506049395031, 4968.950310496921, 4986.102501549756, 4994.600431965434, 4994.750262486861, 4488.079943611552, 4494.752788596593, 4496.887577810581, 4507.188706032945, 4538.115067561794, 5651.773929042793, 5736.397678243808, 5765.596408251466, 5857.384974577693, 5896.262967129109, 6298.118263443122, 6350.206224221973, 6354.110424540568, 6359.268684101387, 6371.044343348535, 4855.344528488679, 5791.0, 5792.247007888947, 5816.912746308809, 5823.854403639943, 5311.681299122065, 5379.193121031876, 5413.593980451482, 5466.180072989084, 5469.535089517435, 5239.816606418757, 6303.338149494421, 6376.872462550816, 6393.840153996187, 6397.552171348054, 6162.0, 6378.394052564999, 6394.552381333262, 6412.1343618612755, 6417.0, 5339.946600533945, 6328.9050664240995, 6376.840578985562, 6403.871922561616, 6408.03879418091, 6246.593971391845, 6249.7500099997305, 6251.218597675291, 6257.7184026719815, 6265.185525881606, 5250.632455728215, 5816.0, 6124.319907527303, 6254.28075771274, 6368.267649220399, 6118.314626315956, 6186.721597528032, 6194.256689197145, 6196.3803619639475, 6200.906986395298, 6109.389061093855, 6131.509479241739, 6137.447629713347, 6156.415140561639, 6166.445019948226, 4871.902561948812, 5892.616979896293, 5899.911501327398, 6040.154378386941, 6055.788047418319, 6024.3674414187635, 6057.091436284416, 6117.57907269851, 6147.415995480422, 6189.381061893955, 6060.937259363361, 6075.605085669417, 6085.421884920926, 6093.6039157454625, 6105.8168254952425], "value": 5709.193414040772, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [527.6095689189999, 555.7360253879408, 601.0983524712927, 606.2997238189889, 635.3709827270998, 32141.517877231832, 39175.0206244844, 39650.60349396505, 39682.80158599203, 39772.0, 42827.0, 43117.137657246916, 43169.78415107928, 43221.0, 43517.0, 9069.773255668628, 9248.12142846432, 9734.65928692496, 9961.501924903761, 9976.600935962553, 11988.700282492964, 12227.877721222763, 12261.693457663541, 12261.816072758871, 12271.0, 11913.046956243523, 12000.279983201035, 12017.459214335377, 12027.458764355624, 12028.398580070962, 11862.288262704222, 11947.163698541062, 11957.641270761891, 11960.820587691192, 11962.880371196263, 11562.190646654737, 11675.883241167565, 11687.590934317343, 11689.41552922359, 11706.063514918786, 41775.74672759822, 42218.36672449916, 42244.78877605623, 42313.94215144615, 42385.36421953673, 3301.8207982731283, 3345.2640419107756, 3413.76103672743, 3461.0828130545424, 3467.167879708877, 25029.749702502922, 28185.0, 29654.258643533867, 30866.0, 30922.845385772926, 2796.482650709605, 2800.075974928269, 2819.9577006344716, 2847.3878116205115, 2873.267316834215, 442284.0, 444262.0, 444990.0, 446083.0, 446719.0, 36905.81547092248, 37430.812845935594, 64409.35590644128, 65946.67026664836, 66156.33843661597, 36058.0, 36182.45726314108, 36197.0, 36212.91361259166, 36225.0, 13766.65583360404, 16193.433229837136, 16378.344866205456, 16381.83618163827, 16382.918085409736, 12966.481340746453, 13145.474181032843, 13850.445982160802, 14203.857961420461, 14228.217448040457, 8398.370122240734, 8413.621387037598, 8415.0, 8479.406441549156, 8495.702650407206, 3504.9299014019575, 3524.0, 3524.0, 3531.682148606638, 3559.555055618048, 940.3699521320732, 1011.6459239266345, 1058.5448257249457, 1098.6594155811724, 1112.1103117505963, 30361.538880660595, 30488.932887348838, 30601.928932487703, 30631.2342191447, 30909.690903090683, 970.6942313171328, 1123.1632433836844, 1193.570314686712, 1227.2391117507202, 1237.1216436330126, 15323.310451029507, 15521.0, 15580.454684086002, 15916.920415397848, 16013.679726405406, 612927.0, 625812.0, 626824.0, 628750.0, 630041.0, 62134.067988980205, 62662.0, 62702.686486567276, 62721.68639156775, 62867.0, 1398.8531204223614, 1431.1985288238411, 1536.9231538423035, 1560.641052557918, 1573.7482002879485, 27977.020804271753, 29131.85434072816, 29492.0, 29502.852485737432, 29677.554836677034, 46886.0, 47257.7637111826, 47291.763541182074, 47362.289565655854, 47492.762536187096, 310081.0, 315215.4239228789, 315774.0, 316537.0, 317975.0, 24315.878420607783, 27119.45761084807, 27188.592171117845, 27638.44723105567, 27707.722922770514, 45607.0, 46043.0, 46861.76569117133, 46994.0, 47671.28493072676, 36696.26607467782, 37349.43975840419, 38082.61917380791, 38155.046123847125, 39119.0, 33796.986090417326, 35579.644203558666, 37270.44094338641, 37644.43533347057, 38248.426273605364, 37142.25715485729, 37592.2481550362, 37622.0, 37910.431343529315, 37952.0, 32188.0, 33190.0, 33276.1680957978, 33335.49996750002, 33406.66593334036, 24707.0, 24967.50064998726, 25264.62103068492, 25471.872640636677, 25536.48927021412], "value": 18952.894907752197, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.9906002349941, 423.616626952608, 423.6335569732183, 425.7062626787517, 428.21850123524564, 20471.795282047176, 21332.253371132014, 21352.35942921711, 21557.67663485049, 22611.321660350175, 12380.319082450484, 12643.241405515675, 17362.0, 21898.89050554749, 21908.0, 5193.80542475231, 5319.547838433728, 5803.158542011406, 6419.64691941943, 6443.742250309982, 6322.114903913456, 6323.715432805512, 6354.618722876619, 6395.424411802936, 6399.744010239585, 6089.45194932456, 6322.0, 6335.461485773715, 6347.428731414172, 6363.9045414318825, 6227.065940108974, 6279.937200628026, 6304.0, 6307.27466341372, 6309.589876658002, 5365.624406291561, 5463.3717122530625, 5562.666240025612, 5569.972150139264, 5582.91625625616, 13986.510472133528, 14003.089799163023, 14177.291135443187, 14234.288285585679, 14241.572752817328, 2149.9892500537553, 2187.245400336897, 2203.5923354179527, 2236.2285011670947, 2246.7416247131628, 10056.899431005742, 10121.746956326151, 10196.49017549127, 10490.790184196274, 10523.000314970066, 1846.8799528030636, 1858.823411775879, 1874.044237438916, 1879.3798046644574, 1880.0, 116625.4168729151, 118868.02818941459, 120269.0, 122691.77308226893, 124479.0, 11858.407079646156, 12022.699432514257, 12111.636650900487, 12292.754144917051, 12311.630651080302, 15572.377104915677, 15784.052956822627, 15804.446844360622, 15843.445479408165, 15848.366065357488, 7994.960025199837, 8908.510031948175, 9296.256299496024, 9366.29752768545, 9488.193503552136, 9459.38513996588, 9459.97280870115, 9483.288753343526, 9487.762805929908, 9573.856392154123, 4909.631777616693, 4930.630202734809, 4934.309196712463, 4946.0, 4973.079980203602, 1777.3512667876391, 2017.878927264369, 2033.3188381892232, 2066.90698918549, 2090.5505316357057, 888.391451855481, 945.7115579748105, 963.2246041936169, 966.5698764050071, 984.3601658921615, 19773.0, 20909.58180836405, 21201.89399052995, 21845.890770546044, 22219.66670499944, 869.7999460124207, 1002.4486532407146, 1005.7787286796893, 1034.6016783538391, 1079.6653037558383, 10995.670129896116, 11458.656240312805, 11470.655880323604, 11481.71295717597, 11492.367919764492, 622855.0, 623896.0, 634954.0, 665911.0, 684907.0, 137038.0, 137903.0, 139436.0, 140158.0, 140213.59786402006, 448.5872996842912, 471.22718741264356, 473.3136951420454, 473.6684320975331, 476.4711170600631, 9791.46146961924, 10813.188570971446, 10898.836517452086, 11080.224384293022, 17918.0, 50684.74657626688, 50800.98398031945, 51107.744461277456, 52299.73850130725, 53022.46977530175, 70271.64864175646, 71449.642751788, 72486.91269631065, 72555.0, 73148.0, 7137.714491420285, 7903.446758726834, 8393.790155246168, 8512.616932237941, 8594.011688655884, 11309.491072901574, 12833.807492887801, 12999.740005199654, 13226.933865330611, 13277.0, 11024.448777560929, 11172.217944744116, 11200.21598488098, 11246.381449020382, 11466.369349685847, 10718.624848130277, 10861.945690271497, 11155.897277873435, 11442.713932151762, 11451.713707157387, 11288.604898828502, 11414.143939204427, 11870.703232419259, 11879.0, 11927.165098443025, 8387.66449342044, 8390.244877961013, 8596.35527335465, 8619.482831030158, 8944.329175311748, 11363.0, 11488.0, 11520.251183673034, 11662.55086431825, 11840.881591183977], "value": 9364.662775734438, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1459.7080583883226, 1612.7661489084082, 1616.5716085237411, 1899.9860148242856, 2024.0790440349642, 8194.590270486475, 8499.78750531237, 8600.182982616658, 8608.483490990537, 8620.913790862089, 8620.827583448327, 8840.071792461795, 8857.379983401148, 8858.202761751458, 8879.733607991771, 2499.6500489931377, 2501.5997440409537, 2529.2665127113114, 2533.138732830837, 2533.2526904563106, 2457.054034196838, 2520.407704189514, 2521.8865151068153, 2525.053105085593, 2527.5703130467864, 2480.032787212979, 2509.4479214572766, 2528.3426309159568, 2530.8861101250395, 2533.7592928671747, 2478.9008439662393, 2529.8482091074593, 2532.126416386353, 2535.024015753948, 2537.746225377466, 2010.3164923925935, 2509.498100379931, 2518.962215566768, 2519.6220566914926, 2526.191618682004, 8621.741347759576, 8822.735317940473, 8854.778630534223, 8944.463332200025, 8960.775980600472, 5428.240046393468, 5639.971800141014, 5641.323041235037, 5660.29246344207, 5670.177824215507, 3580.606133325332, 3652.4703917932015, 3694.9630503695157, 3706.1475860552223, 3733.7199710021855, 1036.1451802263089, 1048.8007278617038, 1052.9684109476652, 1062.8033813744482, 1070.5236169904397, 9732.513374331325, 12156.088293378032, 12162.02703783704, 12193.756124877542, 12194.512219511209, 4610.538946105363, 4682.742449165328, 4705.317728929286, 4773.331733557305, 4793.18515852306, 9489.857652135224, 9495.762605934908, 9500.619975200914, 9503.28725345602, 9543.950165481792, 5438.320209973754, 5490.835274941758, 5492.588055895744, 5499.532539734088, 5503.834884953458, 4566.840160594364, 4739.218029025241, 4868.561829435368, 4978.925316120261, 5081.618878584121, 4100.569440208795, 4159.226557490282, 4212.85255016073, 4239.385289133058, 4253.510846252659, 1355.593322003395, 1376.7040086381276, 1390.9165450073028, 1401.7476854166143, 1416.5679467762225, 956.8803899512561, 964.8504481805192, 983.8966908474507, 994.0606127209862, 1003.7992401519725, 5905.852353691193, 5961.940380596226, 6028.397160284024, 6126.203593532812, 6193.120199107437, 477.2316570321779, 482.1923278508484, 487.096436111009, 487.12804080695867, 492.06016508469366, 3922.254771593445, 4014.297497937849, 4043.332850079763, 4114.033202197481, 4151.605597468236, 10545.261831671709, 10978.231523793258, 11005.229633925548, 11010.0, 11041.39272340013, 9676.371035882787, 10192.439415832201, 10785.622503212508, 10807.0, 10838.241323107306, 7943.880841787378, 8273.958630206811, 8320.251177393942, 8498.447600905922, 8550.358723095793, 5912.61567998096, 6022.638641681513, 6028.0, 6095.542834287535, 6222.937770622236, 9669.951650241705, 9700.0, 9705.320627556004, 9860.901390986286, 9866.802663946824, 9627.277954153607, 9982.35114717541, 10051.798964020825, 10088.646897358558, 10120.443375614412, 6962.373386395248, 7153.2131465537705, 7371.299726525972, 7632.5038872473115, 7751.108622508484, 8279.917200828155, 8321.625526851427, 8349.081601024003, 8374.623141958504, 8389.538575378412, 7243.492955493065, 7334.596597187206, 7373.594452305175, 7455.105387353552, 7481.476296659182, 6251.6561589113035, 7249.420046396381, 7297.63511824396, 7315.707371705285, 7343.559386436831, 7027.402670773042, 7100.715971361088, 7108.786736397709, 7149.106361704787, 7183.461240406886, 4894.314795928502, 4962.503749625153, 5019.272205530105, 5036.974815125901, 5133.332666753298, 5129.923051154161, 5133.512316329943, 5172.379314482287, 5206.245094461357, 5425.294711687613], "value": 4606.780267705977, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.54441415261095, 338.4309288807396, 344.3973047167457, 347.70097715964283, 349.9265154317593, 6478.935210647892, 6664.833379165517, 6682.498812589057, 6684.933150668492, 6696.4642828573715, 6436.292007879129, 6565.113709649196, 6594.241662208848, 6613.239477460093, 6654.500912431563, 4359.2371335016405, 4734.550217729327, 4977.402711674604, 4998.775055122528, 5011.223260394645, 3797.7087790151345, 4394.934075988846, 4780.641451891105, 5024.572911302544, 5024.773885175176, 4794.592459640935, 4979.751012449381, 5001.499850014988, 5032.220005899091, 5050.823221187259, 4972.2044472884345, 4973.900521989576, 4981.950180498185, 4983.401991760976, 4985.576226020793, 4245.681573881971, 4248.978755106235, 4264.552222016675, 4267.786610669454, 4274.935875961863, 6353.110564520971, 6389.041643753428, 6402.295747467775, 6402.807915762534, 6433.581817181868, 5300.840974770763, 5327.573794096463, 5632.943670563324, 5828.417158284137, 5833.854153646193, 5614.719264036823, 6521.902171467432, 7029.258589150236, 7104.360607545294, 7144.46416518758, 4257.723247988902, 4785.760711964388, 4786.808527658889, 4790.353302304214, 4790.736509491977, 7520.285572870624, 7520.774376768706, 7535.510191837513, 7536.660850261751, 7561.470697051209, 4856.028794241166, 4862.222044472867, 4867.0995865764235, 4874.366332376769, 4880.267959806058, 4591.9540804591525, 4741.075490279362, 4929.531694489017, 4956.628252881048, 4960.826371077067, 5634.126710359818, 5640.182173584807, 5646.322441307069, 5652.519535839499, 5707.143928410772, 5432.994895944263, 6764.661766911733, 7105.573665580082, 7115.466340024519, 7119.074520312326, 1569.3173469540786, 1602.166873225916, 1605.6226786705115, 1610.6698126883962, 1630.8124565674862, 856.9914300857037, 1063.2450959818616, 1064.8243039898484, 1066.7706443114769, 1069.5668254356997, 5257.973710131425, 6622.337766223339, 6733.8653226935185, 6744.5616034957575, 6760.3239676032, 5905.852353691193, 7403.185649578542, 7418.0, 7421.4804963653105, 7429.4799364044, 5453.945460545423, 5480.534154596904, 6706.865862682719, 6716.764913228014, 6732.697028633625, 3837.808109594509, 3862.1503269280715, 3912.980435097806, 3935.763854168759, 3960.8415663373144, 6475.967620161869, 7641.083070031632, 7764.572948487775, 7768.961155194188, 7886.0, 7752.030996125484, 7752.8449431012195, 7758.650860711282, 7781.844363112706, 7782.377409807202, 6294.276158241769, 6319.8104056878365, 7846.843063138819, 7875.684972601031, 7876.094249161305, 7258.346748792634, 7526.586037767756, 7537.886931696139, 7551.773446796605, 7568.5458872467825, 7321.524100933422, 7353.558786472829, 7388.039554857834, 7390.9260907390235, 7396.704131834882, 7837.41219408558, 7842.64708088126, 7847.176046515034, 7863.724769633035, 7871.48835325702, 4209.34755112958, 4814.037192561432, 4909.803607855646, 4943.406791185081, 4943.8516844494725, 5932.406759324033, 7038.577685338896, 7370.742024029133, 7375.963120184365, 7420.109586849397, 7045.436365090883, 7059.0, 7063.858722825412, 7068.222495525591, 7103.609301488261, 7101.2898710128575, 7103.431725462054, 7117.3238542338395, 7123.323284287984, 7124.073870397023, 6750.291860205328, 6962.860742785015, 6966.9651651741415, 6973.267806880204, 6990.370866622028, 6930.0, 6991.2659170788365, 7116.8932466014085, 7134.0, 7147.571145731273, 6876.0, 6964.930350696428, 6979.965100174467, 6995.615241161785, 7010.4041156500225], "value": 5175.824254535401, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.6196127420361, 691.9169699636044, 747.2378174262254, 754.4982586579924, 798.9760307190783, 15393.153376564285, 15550.92224538877, 15746.370145194207, 15759.921200393996, 15790.289436975336, 14508.274586270698, 14569.854301456982, 14599.562013139595, 14611.0, 14612.050216735905, 789.2186735132207, 803.7789607857857, 954.766082309835, 974.3325821812045, 998.4907697074508, 992.5235886774352, 993.1111655068709, 1000.8498725191207, 1002.003007008028, 1004.1013293102669, 931.5947562810165, 1056.587930707025, 1060.7189094889861, 1063.016709543673, 1063.191974099682, 835.4945258118842, 844.4975239732349, 848.5799529233008, 850.3197442046369, 1054.4464156317956, 761.466973118819, 773.5552057566922, 774.7017398301695, 775.441681988967, 775.7789030126444, 15596.064236145867, 15700.685986280325, 15708.0, 15717.056976581442, 15755.44855930037, 12886.935565322206, 12900.09699321048, 13131.934340328333, 13197.0, 13226.537071202461, 10219.744506387326, 10267.43529105899, 10268.640597579124, 10337.328073675262, 10811.18916081297, 3586.444101164324, 3595.496630471736, 3644.0, 3676.9632303677154, 3864.768113913174, 83757.58121209416, 84496.0, 84946.15053849445, 85033.14966850315, 88014.5599272006, 9787.895801141744, 9848.162906152915, 9850.064243896819, 9853.605855765833, 10116.54475548602, 22747.0, 22759.88620056889, 22774.20290289832, 22813.85930703458, 22818.885905570365, 6877.552959057645, 7151.533935543202, 7496.7376141834775, 7518.060242469692, 7521.849563008709, 4184.079502509443, 4241.0, 4318.697691161588, 4320.481542214954, 4519.5480451954545, 3738.0, 3796.905077373088, 3807.9238415232094, 3808.8286027128847, 3813.523309586302, 655.5673255651247, 670.969806358715, 671.452765995712, 681.5501768832547, 697.3375293471223, 617.5121653893464, 619.5074915442234, 628.8302158417201, 629.1883470323323, 635.4154178156108, 8097.878531822027, 8314.542700151549, 8556.572171391406, 8707.869381959275, 8886.555672216364, 598.1058317814808, 620.1225266248298, 624.8031869960947, 626.3754867106808, 627.109504503606, 3820.7898565578603, 4021.9798901005306, 4046.1705350403095, 4100.530421840149, 4103.138340948434, 80538.0, 95340.0, 97894.53158202632, 99509.5073573897, 99598.5020074895, 78310.21689783143, 80419.0, 80531.0, 80806.59596702097, 81053.0, 69935.95096073563, 71665.20836979117, 71682.0, 72469.0, 72810.63594681992, 7981.960090199512, 8046.718364857202, 8088.110307866013, 8180.550069745983, 8277.089520152898, 49874.0, 50138.74930625324, 50290.74273143201, 50301.74849125731, 50355.24467133069, 64548.386290343115, 64868.0, 65115.0, 65169.67415163083, 66258.33741662714, 10003.949980250052, 11037.172212083966, 11270.605528806453, 11337.093032557541, 11648.650540483799, 11988.64034078979, 12110.515579376726, 12115.878841211475, 12198.14612977083, 12728.427220774902, 9496.47769372691, 10793.003060538891, 11577.421128943352, 11784.234024788362, 11933.045356371296, 11694.239874408137, 11870.169088163746, 11885.22746021506, 12181.0, 12266.632001039983, 11632.360220187971, 11739.882601173878, 11868.881311186778, 11874.406279685809, 12077.335746533674, 9927.453990030328, 10151.847722284025, 10166.339187952759, 10465.9476702616, 10572.259941804308, 10023.298369114093, 10207.897921020694, 10237.539310731185, 10244.282900197213, 10287.43419111926], "value": 7143.709640956204, "unit": "score"}], "name": "dromaeo_css", "value": 7461.847978028993, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 117.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0, 134.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 122.0, 142.0, 162.0, 117.0, 142.0, 116.0, 154.0, 153.0, 117.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 184.0, 183.0, 189.0, 185.0, 183.0, 184.0, 185.0, 184.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 78.0, 72.0, 153.0, 57.0, 72.0, 81.0, 93.0, 150.0, 55.0], "value": 88.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 139.0, 76.0, 85.0, 85.0, 120.0, 79.0, 76.0, 121.0], "value": 96.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 85.0, 87.0, 85.0, 89.0, 84.0, 88.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 93.0, 92.0, 92.0, 92.0, 93.0, 92.0, 93.0], "value": 92.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 84.0, 85.0, 86.0, 86.0, 86.0, 82.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 61.0, 61.0, 60.0, 62.0, 63.0, 79.0, 56.0, 55.0], "value": 61.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 69.0, 113.0, 105.0, 68.0, 65.0, 66.0, 65.0, 65.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 80.0, 79.0, 78.0, 77.0, 80.0, 79.0, 79.0, 84.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 129.0, 135.0, 123.0, 108.0, 136.0, 139.0, 139.0, 141.0, 126.0], "value": 130.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 156.0, 163.0, 163.0, 160.0, 157.0, 163.0, 163.0, 162.0], "value": 160.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 83.0, 92.0, 90.0, 88.0, 68.0, 65.0, 67.0, 67.0, 66.0], "value": 75.7, "unit": "score"}], "name": "kraken", "value": 1468.3, "alertThreshold": 2.0}]} 01:58:05 INFO - Return code: 0 01:58:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 01:58:05 ERROR - # TBPL SUCCESS # 01:58:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:58:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:58:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:58:05 INFO - 2016-06-07 01:58:05.988 screenresolution[1680:9768] starting screenresolution argv=screenresolution get 01:58:05 INFO - 2016-06-07 01:58:05.990 screenresolution[1680:9768] Display 0: 1600x1200x32@60 01:58:05 INFO - 2016-06-07 01:58:05.996 screenresolution[1681:9771] starting screenresolution argv=screenresolution list 01:58:05 INFO - Available Modes on Display 0 01:58:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:58:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:58:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:58:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:58:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:58:06 INFO - Intel Iris: 01:58:06 INFO - Chipset Model: Intel Iris 01:58:06 INFO - Type: GPU 01:58:06 INFO - Bus: Built-In 01:58:06 INFO - VRAM (Dynamic, Max): 1536 MB 01:58:06 INFO - Vendor: Intel (0x8086) 01:58:06 INFO - Device ID: 0x0a2e 01:58:06 INFO - Revision ID: 0x0009 01:58:06 INFO - Displays: 01:58:06 INFO - Display: 01:58:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:58:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:58:06 INFO - Display Serial Number: 1600x1200 60 01:58:06 INFO - Main Display: Yes 01:58:06 INFO - Mirror: Off 01:58:06 INFO - Online: Yes 01:58:06 INFO - Rotation: Supported 01:58:06 INFO - Return code: 0 01:58:06 INFO - Running post-action listener: _resource_record_post_action 01:58:06 INFO - Running post-run listener: _resource_record_post_run 01:58:07 INFO - Total resource usage - Wall time: 1193s; CPU: 53.0%; Read bytes: 36898304; Write bytes: 493648384; Read time: 241; Write time: 1514 01:58:07 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 182940160; Write bytes: 177239552; Read time: 16506; Write time: 368 01:58:07 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 35976704; Write bytes: 297079808; Read time: 230; Write time: 1089 01:58:07 INFO - Running post-run listener: _upload_blobber_files 01:58:07 INFO - Blob upload gear active. 01:58:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:58:07 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 01:58:07 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:58:07 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:58:07 INFO - (blobuploader) - INFO - Open directory for files ... 01:58:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:58:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:58:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:58:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:58:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:58:09 INFO - (blobuploader) - INFO - Done attempting. 01:58:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:58:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:58:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:58:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:58:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:58:10 INFO - (blobuploader) - INFO - Done attempting. 01:58:10 INFO - (blobuploader) - INFO - Iteration through files over. 01:58:10 INFO - Return code: 0 01:58:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:58:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:58:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/222d6407981fb5d272a491fae69f5f99903c989a2a610f14a52731ba1f89d20a0bf0755af9ff3aa573c3ca2bf5203a763924fb0f9d84db73346c4db91438dad8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a642b0f75bf93db95515bf100a3371c9399242bc628f2b12cebc03b21758872a681d8d31960246c2906c1ff2b30476c2628b18b26f06b44f1bdaef0c30b2f9c"} 01:58:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:58:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:58:10 INFO - Contents: 01:58:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/222d6407981fb5d272a491fae69f5f99903c989a2a610f14a52731ba1f89d20a0bf0755af9ff3aa573c3ca2bf5203a763924fb0f9d84db73346c4db91438dad8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a642b0f75bf93db95515bf100a3371c9399242bc628f2b12cebc03b21758872a681d8d31960246c2906c1ff2b30476c2628b18b26f06b44f1bdaef0c30b2f9c"} 01:58:10 INFO - Running post-run listener: copy_logs_to_upload_dir 01:58:10 INFO - Copying logs to upload dir... 01:58:10 INFO - mkdir: /builds/slave/test/build/upload/logs 01:58:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.016542 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-06-07 01:58:10.112822) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 01:58:10.116475) ========= 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.UniNK3SeP2/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.nKiiX8EZFK/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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/Mozilla-Beta/sha512/222d6407981fb5d272a491fae69f5f99903c989a2a610f14a52731ba1f89d20a0bf0755af9ff3aa573c3ca2bf5203a763924fb0f9d84db73346c4db91438dad8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a642b0f75bf93db95515bf100a3371c9399242bc628f2b12cebc03b21758872a681d8d31960246c2906c1ff2b30476c2628b18b26f06b44f1bdaef0c30b2f9c"} build_url:https://queue.taskcluster.net/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011008 build_url: 'https://queue.taskcluster.net/v1/task/OFqC62uQSLWm-v2RyXJlUw/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/222d6407981fb5d272a491fae69f5f99903c989a2a610f14a52731ba1f89d20a0bf0755af9ff3aa573c3ca2bf5203a763924fb0f9d84db73346c4db91438dad8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a642b0f75bf93db95515bf100a3371c9399242bc628f2b12cebc03b21758872a681d8d31960246c2906c1ff2b30476c2628b18b26f06b44f1bdaef0c30b2f9c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 01:58:10.152244) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:58:10.152585) ========= 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.UniNK3SeP2/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.nKiiX8EZFK/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.005073 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:58:10.206473) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 01:58:10.206800) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 01:58:10.207274) ========= ========= Total master_lag: 0.50 =========