builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0387 starttime: 1465205244.44 results: success (0) buildid: 20160606003129 builduid: 7d903f5d265440108d9a2ff2229e14a9 revision: 8f2c9241267a2b73f55eb366ad20e25c305623e8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 02:27:24.435543) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 02:27:24.436014) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 02:27:24.436325) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 02:27:24.465266) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:27:24.465690) ========= 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.5QuZAVLVIz/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.KPHhAWpNQe/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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-06 02:27:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2016-06-06 02:27:24 (18.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105359 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:27:24.592455) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:27:24.592835) ========= 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.5QuZAVLVIz/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.KPHhAWpNQe/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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.024793 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:27:24.641321) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 02:27:24.641690) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8f2c9241267a2b73f55eb366ad20e25c305623e8 --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-aurora --rev 8f2c9241267a2b73f55eb366ad20e25c305623e8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5QuZAVLVIz/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.KPHhAWpNQe/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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-06 02:27:24,722 truncating revision to first 12 chars 2016-06-06 02:27:24,722 Setting DEBUG logging. 2016-06-06 02:27:24,722 attempt 1/10 2016-06-06 02:27:24,722 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8f2c9241267a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 02:27:26,174 unpacking tar archive at: mozilla-aurora-8f2c9241267a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.711061 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 02:27:26.375297) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 02:27:26.375813) ========= script_repo_revision: 8f2c9241267a2b73f55eb366ad20e25c305623e8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 02:27:26.376333) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 02:27:26.376631) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 02:27:26.394122) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-06-06 02:27:26.394564) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5QuZAVLVIz/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.KPHhAWpNQe/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 02:27:26 INFO - MultiFileLogger online at 20160606 02:27:26 in /builds/slave/test 02:27:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:27:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:27:26 INFO - {'append_to_log': False, 02:27:26 INFO - 'base_work_dir': '/builds/slave/test', 02:27:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:27:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:27:26 INFO - 'branch': 'Mozilla-Aurora', 02:27:26 INFO - 'buildbot_json_path': 'buildprops.json', 02:27:26 INFO - 'config_files': ('talos/mac_config.py',), 02:27:26 INFO - 'default_actions': ('clobber', 02:27:26 INFO - 'read-buildbot-config', 02:27:26 INFO - 'download-and-extract', 02:27:26 INFO - 'populate-webroot', 02:27:26 INFO - 'create-virtualenv', 02:27:26 INFO - 'install', 02:27:26 INFO - 'run-tests'), 02:27:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:27:26 INFO - 'download_minidump_stackwalk': True, 02:27:26 INFO - 'download_symbols': 'ondemand', 02:27:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:27:26 INFO - 'tooltool.py': '/tools/tooltool.py', 02:27:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:27:26 INFO - '/tools/misc-python/virtualenv.py')}, 02:27:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:27:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:27:26 INFO - 'installer_path': 'installer.exe', 02:27:26 INFO - 'log_level': 'info', 02:27:26 INFO - 'log_name': 'talos', 02:27:26 INFO - 'log_to_console': True, 02:27:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:27:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:27:26 INFO - 'opt_config_files': (), 02:27:26 INFO - 'pip_index': False, 02:27:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:26 INFO - 'cmd': ('bash', 02:27:26 INFO - '-c', 02:27:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:26 INFO - 'enabled': True, 02:27:26 INFO - 'halt_on_failure': False, 02:27:26 INFO - 'name': 'check_screen_resolution'},), 02:27:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:26 INFO - 'cmd': ('bash', 02:27:26 INFO - '-c', 02:27:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:26 INFO - 'enabled': True, 02:27:26 INFO - 'halt_on_failure': False, 02:27:26 INFO - 'name': 'check_screen_resolution'},), 02:27:26 INFO - 'run_cmd_checks_enabled': True, 02:27:26 INFO - 'sps_profile': False, 02:27:26 INFO - 'sps_profile_interval': 0, 02:27:26 INFO - 'suite': 'dromaeojs', 02:27:26 INFO - 'system_bits': '32', 02:27:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:27:26 INFO - 'title': 't-yosemite-r7-0387', 02:27:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:27:26 INFO - 'use_talos_json': True, 02:27:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:27:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:27:26 INFO - 'work_dir': 'build'} 02:27:26 INFO - ##### 02:27:26 INFO - ##### Running clobber step. 02:27:26 INFO - ##### 02:27:26 INFO - Running pre-action listener: _resource_record_pre_action 02:27:26 INFO - Running main action method: clobber 02:27:26 INFO - rmtree: /builds/slave/test/build 02:27:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:27:28 INFO - Running post-action listener: _resource_record_post_action 02:27:28 INFO - ##### 02:27:28 INFO - ##### Running read-buildbot-config step. 02:27:28 INFO - ##### 02:27:28 INFO - Running pre-action listener: _resource_record_pre_action 02:27:28 INFO - Running main action method: read_buildbot_config 02:27:28 INFO - Using buildbot properties: 02:27:28 INFO - { 02:27:28 INFO - "project": "", 02:27:28 INFO - "product": "firefox", 02:27:28 INFO - "script_repo_revision": "production", 02:27:28 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:27:28 INFO - "repository": "", 02:27:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:27:28 INFO - "buildid": "20160606003129", 02:27:28 INFO - "pgo_build": "False", 02:27:28 INFO - "basedir": "/builds/slave/test", 02:27:28 INFO - "buildnumber": 30, 02:27:28 INFO - "slavename": "t-yosemite-r7-0387", 02:27:28 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:27:28 INFO - "platform": "yosemite_r7", 02:27:28 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:27:28 INFO - "branch": "mozilla-aurora", 02:27:28 INFO - "repo_path": "releases/mozilla-aurora", 02:27:28 INFO - "revision": "8f2c9241267a2b73f55eb366ad20e25c305623e8", 02:27:28 INFO - "stage_platform": "macosx64", 02:27:28 INFO - "builduid": "7d903f5d265440108d9a2ff2229e14a9", 02:27:28 INFO - "slavebuilddir": "test" 02:27:28 INFO - } 02:27:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:27:28 INFO - Running post-action listener: _resource_record_post_action 02:27:28 INFO - ##### 02:27:28 INFO - ##### Running download-and-extract step. 02:27:28 INFO - ##### 02:27:28 INFO - Running pre-action listener: _resource_record_pre_action 02:27:28 INFO - Running main action method: download_and_extract 02:27:28 INFO - mkdir: /builds/slave/test/build/tests 02:27:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:27:28 INFO - https://queue.taskcluster.net/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:27:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:27:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:27:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:27:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:27:29 INFO - Downloaded 1199 bytes. 02:27:29 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:27:29 INFO - Using the following test package requirements: 02:27:29 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:27:29 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:27:29 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:27:29 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'jsshell-mac.zip'], 02:27:29 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:27:29 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:27:29 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:27:29 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:27:29 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:27:29 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:27:29 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:27:29 INFO - https://queue.taskcluster.net/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:27:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:27:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:27:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:27:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:27:33 INFO - Downloaded 18466895 bytes. 02:27:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:27:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:27:36 INFO - Return code: 0 02:27:36 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:27:36 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')]} 02:27:36 INFO - https://queue.taskcluster.net/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:27:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:27:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:27:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:27:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:27:55 INFO - Downloaded 18466895 bytes. 02:27:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:27:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:27:59 INFO - Return code: 0 02:27:59 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')]} 02:27:59 INFO - https://queue.taskcluster.net/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:27:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:27:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:27:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:27:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:28:00 INFO - Downloaded 10840145 bytes. 02:28:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:28:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:28:00 INFO - Return code: 0 02:28:00 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')]} 02:28:00 INFO - https://queue.taskcluster.net/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:28:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:28:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:28:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:28:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:28:07 INFO - Downloaded 114168336 bytes. 02:28:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:28:07 INFO - mkdir: /builds/slave/test/properties 02:28:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:28:07 INFO - Writing to file /builds/slave/test/properties/build_url 02:28:07 INFO - Contents: 02:28:07 INFO - build_url:https://queue.taskcluster.net/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:28:08 INFO - Running post-action listener: _resource_record_post_action 02:28:08 INFO - Running post-action listener: set_extra_try_arguments 02:28:08 INFO - ##### 02:28:08 INFO - ##### Running populate-webroot step. 02:28:08 INFO - ##### 02:28:08 INFO - Running pre-action listener: _resource_record_pre_action 02:28:08 INFO - Running main action method: populate_webroot 02:28:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:28:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:28:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:28:08 INFO - '--tppagecycles', 02:28:08 INFO - '7'], 02:28:08 INFO - 'tests': ['tsvgm']}}, 02:28:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:28:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:28:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:28:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:28:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:28:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:28:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:08 INFO - 'talos_options': ['--disable-e10s'], 02:28:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:28:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:28:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:08 INFO - 'talos_options': ['--disable-e10s'], 02:28:08 INFO - 'tests': ['damp', 'tps']}, 02:28:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:08 INFO - 'tests': ['damp', 'tps']}, 02:28:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:28:08 INFO - 'tests': ['dromaeo_dom']}, 02:28:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:28:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:28:08 INFO - 'tests': ['a11yr', 02:28:08 INFO - 'ts_paint', 02:28:08 INFO - 'tpaint', 02:28:08 INFO - 'sessionrestore', 02:28:08 INFO - 'sessionrestore_no_auto_restore', 02:28:08 INFO - 'tabpaint']}, 02:28:08 INFO - 'other-e10s': {'tests': ['a11yr', 02:28:08 INFO - 'ts_paint', 02:28:08 INFO - 'tpaint', 02:28:08 INFO - 'sessionrestore', 02:28:08 INFO - 'sessionrestore_no_auto_restore', 02:28:08 INFO - 'tabpaint']}, 02:28:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:28:08 INFO - 'ts_paint', 02:28:08 INFO - 'tpaint', 02:28:08 INFO - 'sessionrestore', 02:28:08 INFO - 'sessionrestore_no_auto_restore', 02:28:08 INFO - 'tabpaint']}, 02:28:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:28:08 INFO - 'ts_paint', 02:28:08 INFO - 'tpaint', 02:28:08 INFO - 'sessionrestore', 02:28:08 INFO - 'sessionrestore_no_auto_restore', 02:28:08 INFO - 'tabpaint']}, 02:28:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:28:08 INFO - 'tests': ['a11yr', 02:28:08 INFO - 'ts_paint', 02:28:08 INFO - 'tpaint', 02:28:08 INFO - 'sessionrestore', 02:28:08 INFO - 'sessionrestore_no_auto_restore', 02:28:08 INFO - 'tabpaint']}, 02:28:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:28:08 INFO - 'tests': ['a11yr', 02:28:08 INFO - 'ts_paint', 02:28:08 INFO - 'tpaint', 02:28:08 INFO - 'sessionrestore', 02:28:08 INFO - 'sessionrestore_no_auto_restore', 02:28:08 INFO - 'tabpaint']}, 02:28:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:28:08 INFO - 'tests': ['tsvgx', 02:28:08 INFO - 'tsvgr_opacity', 02:28:08 INFO - 'tart', 02:28:08 INFO - 'tscrollx', 02:28:08 INFO - 'cart']}, 02:28:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:28:08 INFO - 'tsvgr_opacity', 02:28:08 INFO - 'tart', 02:28:08 INFO - 'tscrollx', 02:28:08 INFO - 'cart']}, 02:28:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:08 INFO - 'talos_options': ['--disable-e10s'], 02:28:08 INFO - 'tests': ['tp5o']}, 02:28:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:08 INFO - 'tests': ['tp5o']}, 02:28:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:08 INFO - 'talos_options': ['--disable-e10s', 02:28:08 INFO - '--xperf_path', 02:28:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:28:08 INFO - 'tests': ['tp5n']}, 02:28:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:08 INFO - 'talos_options': ['--xperf_path', 02:28:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:28:08 INFO - 'tests': ['tp5n']}}, 02:28:08 INFO - 'talos.zip': {'path': '', 02:28:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:28:08 INFO - Running post-action listener: _resource_record_post_action 02:28:08 INFO - ##### 02:28:08 INFO - ##### Running create-virtualenv step. 02:28:08 INFO - ##### 02:28:08 INFO - Running pre-action listener: _resource_record_pre_action 02:28:08 INFO - Running main action method: create_virtualenv 02:28:08 INFO - Creating virtualenv /builds/slave/test/build/venv 02:28:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:28:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:28:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:28:09 INFO - Using real prefix '/tools/python27' 02:28:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:28:09 INFO - Installing distribute.............................................................................................................................................................................................done. 02:28:12 INFO - Installing pip.................done. 02:28:12 INFO - Return code: 0 02:28:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:28:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:28:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108436a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108446420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2ca54c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d5e00>, '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 0x10813edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d0b20>, '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.5QuZAVLVIz/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.KPHhAWpNQe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 02:28:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:28:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:28:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QuZAVLVIz/Render', 02:28:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:12 INFO - 'HOME': '/Users/cltbld', 02:28:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:12 INFO - 'LOGNAME': 'cltbld', 02:28:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:12 INFO - 'MOZ_NO_REMOTE': '1', 02:28:12 INFO - 'NO_EM_RESTART': '1', 02:28:12 INFO - 'PAGER': '/bin/cat', 02:28:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:12 INFO - 'PWD': '/builds/slave/test', 02:28:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:12 INFO - 'SHELL': '/bin/bash', 02:28:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KPHhAWpNQe/Listeners', 02:28:12 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 02:28:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:12 INFO - 'USER': 'cltbld', 02:28:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:12 INFO - 'XPC_FLAGS': '0x0', 02:28:12 INFO - 'XPC_SERVICE_NAME': '0', 02:28:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:28:12 INFO - Cleaning up... 02:28:12 INFO - Return code: 0 02:28:12 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:28:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:28:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:28:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108436a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108446420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2ca54c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d5e00>, '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 0x10813edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d0b20>, '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.5QuZAVLVIz/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.KPHhAWpNQe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 02:28:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:28:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:28:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QuZAVLVIz/Render', 02:28:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:13 INFO - 'HOME': '/Users/cltbld', 02:28:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:13 INFO - 'LOGNAME': 'cltbld', 02:28:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:13 INFO - 'MOZ_NO_REMOTE': '1', 02:28:13 INFO - 'NO_EM_RESTART': '1', 02:28:13 INFO - 'PAGER': '/bin/cat', 02:28:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:13 INFO - 'PWD': '/builds/slave/test', 02:28:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:13 INFO - 'SHELL': '/bin/bash', 02:28:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KPHhAWpNQe/Listeners', 02:28:13 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 02:28:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:13 INFO - 'USER': 'cltbld', 02:28:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:13 INFO - 'XPC_FLAGS': '0x0', 02:28:13 INFO - 'XPC_SERVICE_NAME': '0', 02:28:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:13 INFO - Downloading/unpacking psutil>=0.7.1 02:28:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:28:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:16 INFO - Installing collected packages: psutil 02:28:16 INFO - Running setup.py install for psutil 02:28:16 INFO - building 'psutil._psutil_osx' extension 02:28:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:28:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:28:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:28:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:28:17 INFO - building 'psutil._psutil_posix' extension 02:28:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:28:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:28:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:28:17 INFO - ^ 02:28:17 INFO - 1 warning generated. 02:28:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:28:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:17 INFO - Successfully installed psutil 02:28:17 INFO - Cleaning up... 02:28:17 INFO - Return code: 0 02:28:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:28:17 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')]} 02:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:17 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')]} 02:28:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:17 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 0x108436a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108446420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2ca54c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d5e00>, '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 0x10813edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d0b20>, '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.5QuZAVLVIz/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.KPHhAWpNQe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 02:28:17 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 02:28:17 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 02:28:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QuZAVLVIz/Render', 02:28:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:17 INFO - 'HOME': '/Users/cltbld', 02:28:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:17 INFO - 'LOGNAME': 'cltbld', 02:28:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:17 INFO - 'MOZ_NO_REMOTE': '1', 02:28:17 INFO - 'NO_EM_RESTART': '1', 02:28:17 INFO - 'PAGER': '/bin/cat', 02:28:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:17 INFO - 'PWD': '/builds/slave/test', 02:28:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:17 INFO - 'SHELL': '/bin/bash', 02:28:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KPHhAWpNQe/Listeners', 02:28:17 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 02:28:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:17 INFO - 'USER': 'cltbld', 02:28:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:17 INFO - 'XPC_FLAGS': '0x0', 02:28:17 INFO - 'XPC_SERVICE_NAME': '0', 02:28:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:28:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:28:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:28:20 INFO - Installing collected packages: mozsystemmonitor 02:28:20 INFO - Running setup.py install for mozsystemmonitor 02:28:20 INFO - Successfully installed mozsystemmonitor 02:28:20 INFO - Cleaning up... 02:28:20 INFO - Return code: 0 02:28:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:28: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')]} 02:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28: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')]} 02:28:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:20 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 0x108436a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108446420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2ca54c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d5e00>, '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 0x10813edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d0b20>, '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.5QuZAVLVIz/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.KPHhAWpNQe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 02:28:20 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 02:28:20 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 02:28:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QuZAVLVIz/Render', 02:28:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:20 INFO - 'HOME': '/Users/cltbld', 02:28:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:20 INFO - 'LOGNAME': 'cltbld', 02:28:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:20 INFO - 'MOZ_NO_REMOTE': '1', 02:28:20 INFO - 'NO_EM_RESTART': '1', 02:28:20 INFO - 'PAGER': '/bin/cat', 02:28:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:20 INFO - 'PWD': '/builds/slave/test', 02:28:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:20 INFO - 'SHELL': '/bin/bash', 02:28:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KPHhAWpNQe/Listeners', 02:28:20 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 02:28:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:20 INFO - 'USER': 'cltbld', 02:28:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:20 INFO - 'XPC_FLAGS': '0x0', 02:28:20 INFO - 'XPC_SERVICE_NAME': '0', 02:28:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:20 INFO - Downloading/unpacking blobuploader==1.2.4 02:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:22 INFO - Downloading blobuploader-1.2.4.tar.gz 02:28:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:28:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:28:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:24 INFO - Downloading docopt-0.6.1.tar.gz 02:28:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:28:24 INFO - Installing collected packages: blobuploader, requests, docopt 02:28:24 INFO - Running setup.py install for blobuploader 02:28:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:28:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:28:24 INFO - Running setup.py install for requests 02:28:24 INFO - Running setup.py install for docopt 02:28:25 INFO - Successfully installed blobuploader requests docopt 02:28:25 INFO - Cleaning up... 02:28:25 INFO - Return code: 0 02:28:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28: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')]} 02:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28: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')]} 02:28:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:25 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 0x108436a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108446420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2ca54c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d5e00>, '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 0x10813edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d0b20>, '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.5QuZAVLVIz/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.KPHhAWpNQe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 02:28:25 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 02:28:25 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 02:28:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QuZAVLVIz/Render', 02:28:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:25 INFO - 'HOME': '/Users/cltbld', 02:28:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:25 INFO - 'LOGNAME': 'cltbld', 02:28:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:25 INFO - 'MOZ_NO_REMOTE': '1', 02:28:25 INFO - 'NO_EM_RESTART': '1', 02:28:25 INFO - 'PAGER': '/bin/cat', 02:28:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:25 INFO - 'PWD': '/builds/slave/test', 02:28:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:25 INFO - 'SHELL': '/bin/bash', 02:28:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KPHhAWpNQe/Listeners', 02:28:25 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 02:28:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:25 INFO - 'USER': 'cltbld', 02:28:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:25 INFO - 'XPC_FLAGS': '0x0', 02:28:25 INFO - 'XPC_SERVICE_NAME': '0', 02:28:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:25 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-yZ3Fbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:25 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-uHoJnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:26 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-R0DBRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:26 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-2YX021-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:26 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-K27vGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:26 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-5H1G81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:26 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-HGSBju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:26 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-uJiGKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:26 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-89__7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:26 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-hIpna7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:26 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-zuEz9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:26 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-FW9aTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:27 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-ilrC0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:27 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-VdoILv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:27 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-xtO6rj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:27 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-kTtcFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:27 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-gf4McY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:28:27 INFO - Running setup.py install for manifestparser 02:28:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:28:27 INFO - Running setup.py install for mozcrash 02:28:27 INFO - Running setup.py install for mozdebug 02:28:27 INFO - Running setup.py install for mozdevice 02:28:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:28:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:28:28 INFO - Running setup.py install for mozfile 02:28:28 INFO - Running setup.py install for mozhttpd 02:28:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:28:28 INFO - Running setup.py install for mozinfo 02:28:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:28:28 INFO - Running setup.py install for mozInstall 02:28:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:28:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:28:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:28:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:28:28 INFO - Running setup.py install for mozleak 02:28:28 INFO - Running setup.py install for mozlog 02:28:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:28:29 INFO - Running setup.py install for moznetwork 02:28:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:28:29 INFO - Running setup.py install for mozprocess 02:28:29 INFO - Running setup.py install for mozprofile 02:28:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:28:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:28:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:28:29 INFO - Running setup.py install for mozrunner 02:28:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:28:29 INFO - Running setup.py install for mozscreenshot 02:28:29 INFO - Running setup.py install for moztest 02:28:29 INFO - Running setup.py install for mozversion 02:28:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:28:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:28:30 INFO - Cleaning up... 02:28:30 INFO - Return code: 0 02:28:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:30 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 0x108436a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108446420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2ca54c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d5e00>, '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 0x10813edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d0b20>, '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.5QuZAVLVIz/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.KPHhAWpNQe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 02:28:30 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 02:28:30 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 02:28:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QuZAVLVIz/Render', 02:28:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:30 INFO - 'HOME': '/Users/cltbld', 02:28:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:30 INFO - 'LOGNAME': 'cltbld', 02:28:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:30 INFO - 'MOZ_NO_REMOTE': '1', 02:28:30 INFO - 'NO_EM_RESTART': '1', 02:28:30 INFO - 'PAGER': '/bin/cat', 02:28:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:30 INFO - 'PWD': '/builds/slave/test', 02:28:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:30 INFO - 'SHELL': '/bin/bash', 02:28:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KPHhAWpNQe/Listeners', 02:28:30 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 02:28:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:30 INFO - 'USER': 'cltbld', 02:28:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:30 INFO - 'XPC_FLAGS': '0x0', 02:28:30 INFO - 'XPC_SERVICE_NAME': '0', 02:28:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:30 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-NYPre6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:30 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)) 02:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:30 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-KRdI9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:30 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)) 02:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:30 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-PLNEQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:30 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)) 02:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:30 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-khEFOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:30 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)) 02:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:30 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-kAWklO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:30 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)) 02:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:30 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-O5uLxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:31 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)) 02:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:31 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-hcRaeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:31 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)) 02:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:31 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-lVcQPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:31 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)) 02:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:31 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-OTBqED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:31 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)) 02:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:31 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-fSl3v1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:31 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)) 02:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:31 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-97jRYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:31 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)) 02:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:31 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-xX3H5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:31 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)) 02:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:31 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-QlhFzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:31 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)) 02:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:31 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-dDGWxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:31 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)) 02:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:31 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-bsn36T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:32 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)) 02:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:32 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-UQHJBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:32 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)) 02:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:32 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-yxrDQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:32 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)) 02:28:32 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)) 02:28:32 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)) 02:28:32 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)) 02:28:32 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)) 02:28:32 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)) 02:28:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:28:32 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 02:28:32 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 02:28:32 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 02:28:32 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 02:28:32 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 02:28:32 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 02:28:35 INFO - Downloading blessings-1.6.tar.gz 02:28:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:28:35 INFO - Installing collected packages: blessings 02:28:35 INFO - Running setup.py install for blessings 02:28:35 INFO - Successfully installed blessings 02:28:35 INFO - Cleaning up... 02:28:35 INFO - Return code: 0 02:28:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:28:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:36 INFO - Reading from file tmpfile_stdout 02:28:36 INFO - Current package versions: 02:28:36 INFO - blessings == 1.6 02:28:36 INFO - blobuploader == 1.2.4 02:28:36 INFO - docopt == 0.6.1 02:28:36 INFO - manifestparser == 1.1 02:28:36 INFO - mozInstall == 1.12 02:28:36 INFO - mozcrash == 0.17 02:28:36 INFO - mozdebug == 0.1 02:28:36 INFO - mozdevice == 0.48 02:28:36 INFO - mozfile == 1.2 02:28:36 INFO - mozhttpd == 0.7 02:28:36 INFO - mozinfo == 0.9 02:28:36 INFO - mozleak == 0.1 02:28:36 INFO - mozlog == 3.1 02:28:36 INFO - moznetwork == 0.27 02:28:36 INFO - mozprocess == 0.22 02:28:36 INFO - mozprofile == 0.28 02:28:36 INFO - mozrunner == 6.11 02:28:36 INFO - mozscreenshot == 0.1 02:28:36 INFO - mozsystemmonitor == 0.0 02:28:36 INFO - moztest == 0.7 02:28:36 INFO - mozversion == 1.4 02:28:36 INFO - psutil == 3.1.1 02:28:36 INFO - requests == 1.2.3 02:28:36 INFO - wsgiref == 0.1.2 02:28:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:36 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')]} 02:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:36 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')]} 02:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:36 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 0x108436a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108446420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2ca54c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d5e00>, '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 0x10813edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d0b20>, '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.5QuZAVLVIz/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.KPHhAWpNQe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 02:28:36 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 02:28:36 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 02:28:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QuZAVLVIz/Render', 02:28:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:36 INFO - 'HOME': '/Users/cltbld', 02:28:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:36 INFO - 'LOGNAME': 'cltbld', 02:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:36 INFO - 'MOZ_NO_REMOTE': '1', 02:28:36 INFO - 'NO_EM_RESTART': '1', 02:28:36 INFO - 'PAGER': '/bin/cat', 02:28:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:36 INFO - 'PWD': '/builds/slave/test', 02:28:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:36 INFO - 'SHELL': '/bin/bash', 02:28:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KPHhAWpNQe/Listeners', 02:28:36 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 02:28:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:36 INFO - 'USER': 'cltbld', 02:28:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:36 INFO - 'XPC_FLAGS': '0x0', 02:28:36 INFO - 'XPC_SERVICE_NAME': '0', 02:28:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:36 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)) 02:28:36 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)) 02:28:36 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)) 02:28:36 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)) 02:28:36 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)) 02:28:36 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)) 02:28:36 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)) 02:28:36 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)) 02:28:36 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)) 02:28:36 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)) 02:28:36 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)) 02:28:36 INFO - Cleaning up... 02:28:36 INFO - Return code: 0 02:28:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:28:36 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')]} 02:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:36 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')]} 02:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:36 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 0x108436a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108446420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2ca54c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d5e00>, '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 0x10813edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d0b20>, '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.5QuZAVLVIz/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.KPHhAWpNQe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 02:28:36 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 02:28:36 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 02:28:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QuZAVLVIz/Render', 02:28:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:36 INFO - 'HOME': '/Users/cltbld', 02:28:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:36 INFO - 'LOGNAME': 'cltbld', 02:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:36 INFO - 'MOZ_NO_REMOTE': '1', 02:28:36 INFO - 'NO_EM_RESTART': '1', 02:28:36 INFO - 'PAGER': '/bin/cat', 02:28:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:36 INFO - 'PWD': '/builds/slave/test', 02:28:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:36 INFO - 'SHELL': '/bin/bash', 02:28:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KPHhAWpNQe/Listeners', 02:28:36 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 02:28:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:36 INFO - 'USER': 'cltbld', 02:28:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:36 INFO - 'XPC_FLAGS': '0x0', 02:28:36 INFO - 'XPC_SERVICE_NAME': '0', 02:28:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:36 INFO - Downloading/unpacking jsonschema 02:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:28:39 INFO - Downloading/unpacking functools32 (from jsonschema) 02:28:39 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 02:28:39 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 02:28:39 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 02:28:39 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 02:28:39 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 02:28:39 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 02:28:39 INFO - Downloading functools32-3.2.3-2.tar.gz 02:28:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:28:39 INFO - warning: no files found matching '*.txt' 02:28:39 INFO - no previously-included directories found matching 'build' 02:28:39 INFO - no previously-included directories found matching 'dist' 02:28:39 INFO - no previously-included directories found matching '.git*' 02:28:39 INFO - Installing collected packages: jsonschema, functools32 02:28:39 INFO - Running setup.py install for functools32 02:28:39 INFO - warning: no files found matching '*.txt' 02:28:39 INFO - no previously-included directories found matching 'build' 02:28:39 INFO - no previously-included directories found matching 'dist' 02:28:39 INFO - no previously-included directories found matching '.git*' 02:28:39 INFO - Successfully installed jsonschema functools32 02:28:39 INFO - Cleaning up... 02:28:39 INFO - Return code: 0 02:28:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:28:39 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')]} 02:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:39 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')]} 02:28:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:39 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 0x108436a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108446420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2ca54c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d5e00>, '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 0x10813edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d0b20>, '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.5QuZAVLVIz/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.KPHhAWpNQe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 02:28:39 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 02:28:39 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 02:28:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QuZAVLVIz/Render', 02:28:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:39 INFO - 'HOME': '/Users/cltbld', 02:28:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:39 INFO - 'LOGNAME': 'cltbld', 02:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:39 INFO - 'MOZ_NO_REMOTE': '1', 02:28:39 INFO - 'NO_EM_RESTART': '1', 02:28:39 INFO - 'PAGER': '/bin/cat', 02:28:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:39 INFO - 'PWD': '/builds/slave/test', 02:28:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:39 INFO - 'SHELL': '/bin/bash', 02:28:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KPHhAWpNQe/Listeners', 02:28:39 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 02:28:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:39 INFO - 'USER': 'cltbld', 02:28:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:39 INFO - 'XPC_FLAGS': '0x0', 02:28:39 INFO - 'XPC_SERVICE_NAME': '0', 02:28:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:40 INFO - Downloading/unpacking flake8 02:28:40 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 02:28:40 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 02:28:40 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 02:28:40 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 02:28:40 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 02:28:40 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 02:28:42 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:28:42 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:28:42 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 02:28:42 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 02:28:42 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 02:28:42 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 02:28:42 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 02:28:42 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 02:28:42 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:28:42 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:28:42 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 02:28:42 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 02:28:42 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 02:28:42 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 02:28:42 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 02:28:42 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 02:28:43 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:28:43 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 02:28:43 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 02:28:43 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 02:28:43 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 02:28:43 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 02:28:43 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 02:28:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:28:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:28:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:28:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:28:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:28:43 INFO - no previously-included directories found matching 'docs/_build' 02:28:43 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:28:43 INFO - Running setup.py install for pep8 02:28:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:28:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:28:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:28:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:28:43 INFO - no previously-included directories found matching 'docs/_build' 02:28:43 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:28:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:28:43 INFO - Cleaning up... 02:28:44 INFO - Return code: 0 02:28:44 INFO - Running post-action listener: _resource_record_post_action 02:28:44 INFO - Running post-action listener: _start_resource_monitoring 02:28:44 INFO - Starting resource monitoring. 02:28:44 INFO - ##### 02:28:44 INFO - ##### Running install step. 02:28:44 INFO - ##### 02:28:44 INFO - Running pre-action listener: _resource_record_pre_action 02:28:44 INFO - Running main action method: install 02:28:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:44 INFO - Reading from file tmpfile_stdout 02:28:44 INFO - Detecting whether we're running mozinstall >=1.0... 02:28:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:28:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:28:44 INFO - Reading from file tmpfile_stdout 02:28:44 INFO - Output received: 02:28:44 INFO - Usage: mozinstall [options] installer 02:28:44 INFO - Options: 02:28:44 INFO - -h, --help show this help message and exit 02:28:44 INFO - -d DEST, --destination=DEST 02:28:44 INFO - Directory to install application into. [default: 02:28:44 INFO - "/builds/slave/test"] 02:28:44 INFO - --app=APP Application being installed. [default: firefox] 02:28:44 INFO - mkdir: /builds/slave/test/build/application 02:28:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:28:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:29:16 INFO - Reading from file tmpfile_stdout 02:29:17 INFO - Output received: 02:29:17 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:29:17 INFO - Running post-action listener: _resource_record_post_action 02:29:17 INFO - ##### 02:29:17 INFO - ##### Running run-tests step. 02:29:17 INFO - ##### 02:29:17 INFO - Running pre-action listener: _resource_record_pre_action 02:29:17 INFO - Running main action method: run_tests 02:29:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:29:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:29:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:29:17 INFO - 2016-06-06 02:29:17.015 screenresolution[1644:7471] starting screenresolution argv=screenresolution get 02:29:17 INFO - 2016-06-06 02:29:17.018 screenresolution[1644:7471] Display 0: 1600x1200x32@60 02:29:17 INFO - 2016-06-06 02:29:17.024 screenresolution[1645:7473] starting screenresolution argv=screenresolution list 02:29:17 INFO - Available Modes on Display 0 02:29:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:29:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:29:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:29:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:29:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:29:17 INFO - Intel Iris: 02:29:17 INFO - Chipset Model: Intel Iris 02:29:17 INFO - Type: GPU 02:29:17 INFO - Bus: Built-In 02:29:17 INFO - VRAM (Dynamic, Max): 1536 MB 02:29:17 INFO - Vendor: Intel (0x8086) 02:29:17 INFO - Device ID: 0x0a2e 02:29:17 INFO - Revision ID: 0x0009 02:29:17 INFO - Displays: 02:29:17 INFO - Display: 02:29:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:29:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:29:17 INFO - Display Serial Number: 1600x1200 60 02:29:17 INFO - Main Display: Yes 02:29:17 INFO - Mirror: Off 02:29:17 INFO - Online: Yes 02:29:17 INFO - Rotation: Supported 02:29:17 INFO - Return code: 0 02:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:29:17 INFO - Python 2.7.3 02:29:17 INFO - Return code: 0 02:29:17 INFO - grabbing minidump binary from tooltool 02:29:17 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')]} 02:29:17 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 0x1082d5e00>, '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 0x10813edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:29:17 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 02:29:17 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 02:29:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:29:17 INFO - Return code: 0 02:29:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:29:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:29:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:29:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:29:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:29:18 INFO - Return code: 0 02:29:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0387', '--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 02:29:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0387 --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 02:29:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QuZAVLVIz/Render', 02:29:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:18 INFO - 'HOME': '/Users/cltbld', 02:29:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:18 INFO - 'LOGNAME': 'cltbld', 02:29:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:29:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:18 INFO - 'MOZ_NO_REMOTE': '1', 02:29:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:29:18 INFO - 'NO_EM_RESTART': '1', 02:29:18 INFO - 'PAGER': '/bin/cat', 02:29:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:18 INFO - 'PWD': '/builds/slave/test', 02:29:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:29:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:18 INFO - 'SHELL': '/bin/bash', 02:29:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KPHhAWpNQe/Listeners', 02:29:18 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 02:29:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:18 INFO - 'USER': 'cltbld', 02:29:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:18 INFO - 'XPC_FLAGS': '0x0', 02:29:18 INFO - 'XPC_SERVICE_NAME': '0', 02:29:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0387', '--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 02:29:18 INFO - mozversion application_buildid: 20160606003129 02:29:18 INFO - mozversion application_changeset: 8f2c9241267a2b73f55eb366ad20e25c305623e8 02:29:18 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:29:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:29:18 INFO - mozversion application_name: Firefox 02:29:18 INFO - mozversion application_remotingname: firefox-dev 02:29:18 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:29:18 INFO - mozversion application_vendor: Mozilla 02:29:18 INFO - mozversion application_version: 48.0a2 02:29:18 INFO - mozversion platform_buildid: 20160606003129 02:29:18 INFO - mozversion platform_changeset: 8f2c9241267a2b73f55eb366ad20e25c305623e8 02:29:18 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:29:18 INFO - mozversion platform_version: 48.0a2 02:29:18 INFO - using testdate: 1465205358 02:29:18 INFO - actual date: 1465205358 02:29:18 INFO - starting webserver on 'localhost:49273' 02:29:18 INFO - SUITE-START | Running 2 tests 02:29:18 INFO - TEST-START | dromaeo_css 02:29:18 INFO - operating with platform_type : mac_ 02:29:18 INFO - Initialising browser for dromaeo_css test... 02:29:18 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpCbbChM/profile http://localhost:49273/getInfo.html) 02:29:21 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 02:29:21 INFO - PROCESS | 1663 | colorDepth:24 02:29:21 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 02:29:21 INFO - PROCESS | 1663 | __metrics 02:29:21 INFO - PROCESS | 1663 | 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" 02:29:21 ERROR - PROCESS | 1663 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:29:21 INFO - TEST-INFO | 1663: exit 0 02:29:21 INFO - Browser initialized. 02:29:21 INFO - Running cycle 1/1 for dromaeo_css test... 02:29:21 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpCbbChM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:29:33 INFO - PROCESS | 1665 | RSS: Main: 211615744 02:29:33 INFO - PROCESS | 1665 | 02:30:25 INFO - PROCESS | 1665 | 1465205425162 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] 02:32:35 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 02:32:37 INFO - PROCESS | 1665 | RSS: Main: 351920128 02:32:37 INFO - PROCESS | 1665 | 02:35:40 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 02:35:40 INFO - PROCESS | 1665 | RSS: Main: 355520512 02:35:40 INFO - PROCESS | 1665 | 02:38:42 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 02:38:43 INFO - PROCESS | 1665 | RSS: Main: 359837696 02:38:43 INFO - PROCESS | 1665 | 02:41:45 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 02:41:46 INFO - PROCESS | 1665 | RSS: Main: 359059456 02:41:46 INFO - PROCESS | 1665 | 02:44:49 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 02:44:50 INFO - PROCESS | 1665 | RSS: Main: 327700480 02:44:50 INFO - PROCESS | 1665 | 02:47:52 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 02:47:53 INFO - PROCESS | 1665 | RSS: Main: 365535232 02:47:53 INFO - PROCESS | 1665 | 02:47:53 INFO - PROCESS | 1665 | __start_tp_report 02:47:53 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 02:47:53 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 02:47:53 INFO - PROCESS | 1665 | |i|pagename|runs| 02:47:53 INFO - PROCESS | 1665 | |0;dojo.html;3261.62491313499;3325.4014277430083;3331.400347937371;3358.546596209511;3378.949315760264;5447.319085114365;5451.863703407412;5455.427180146082;5459.426760190182;5466.9179962300495;5474.835754927359;5525.640833345829;5532.640378375413;5534.197541356501;5555.805546805863;4912.38595175603;4951.183054795954;5024.673396229234;5059.822906198284;5113.437521872591;4963.950360496403;5034.244863270502;5049.9747501262445;5053.77258023388;5063.670861393997;4961.156603377434;5009.674371165881;5013.849584512453;5034.0183664185315;5055.494450554934;3872.9419058714;5053.545180933734;5067.037262920034;5070.442251352349;5088.414832294259;4900.485449027837;4901.5588597026435;4994.300797888299;4999.300097986248;5061.190209566452;5290.709011004393;5291.629585928987;5318.388385335697;5330.653507521999;5379.193121031876;5881.70591470429;5953.2856057272975;5962.403759624046;5973.462388385023;6007.579469437142;4723.338732577442;5984.73068711909;6094.939050609526;6103.786367477117;6104.053871649901;5565.749541270652;5603.691796951207;5616.5787565932305;5617.297837770278;5652.463016013472;6240.937590624127;6246;6257.7184026718;6259.7496100156395;6288.685565721696;4901.632377571696;5780.421957804186;5867.295924489089;5892.410758924159;5938.020226662638;6106.096658337741;6108.297545782292;6120.540959428061;6139.324674285915;6151.0773383992755;5593.188987596775;5679.119736440858;6021.849453763691;6024.036154215392;6033.396660333931;5863.882722345615;5956.16613674086;5970.850728731816;5993.160957465958;6064.575479716377;4745.786439610226;4764.928526072112;4786.44955830084;4855.975720121377;4857.635677324215;4257.957420425756;4300.440942677432;4302.118065796504;4309.59058889405;4334.869953901388;5528.391876893538;5540.7783688652;5545.362283337387;5563.304586926634;5570.248016517799;5947.6431414115295;6011.098335249748;6036.6679832609625;6058.727357268934;6069.119977603223;5472.26124473199;5495.780168793283;5503.3120859892515;5510.366307874566;5512.751926163332;5183.248428977777;5203.947960520422;5220.660657057279;5232.502912223333;5233.685978841282;5228.9738551307;6228.314885362606;6283.622982621057;6354.650494222862;6384.106225128486;6052.788152414821;6088.26940767093;6095.908561371494;6099.207103076572;6103.969480152571;6328.936710632835;6329.778457753957;6338.144350512806;6343.397377249154;6349;5749.9712501437225;5844.4155584442915;5907.793227237026;5995.280566331893;6020.367861374668;5017.974910125426;6134.601250918676;6136.386361363828;6147.938520614736;6150.907736383869;5916.408359164049;5926.348101708895;5945.019071853096;5946.405359464019;5952.702364881825;5751.223584816164;5783.334916484658;5851.239338885917;5853.970730146322;5888.793892213752;4519.344695019269;4819.783109759999;5799.884002320015;5807.738651760766;5822.5633077518505;5934.465898069022;5967.313758917607;5968.850778730566;5972.970135149297;5995.46040856325;5589.329280486205;5644.3508996465935;5680.71596420169;5686.630369026;5696.544276457957 02:47:53 INFO - PROCESS | 1665 | |1;ext.html;499.80507602035203;500.1272779000642;528.719778517386;568.6701713006456;584.0305093544716;31234.375312493765;31822;39698.801505992466;40871;40996.38505422421;42995.78502107493;43556.69329920108;43596.78201608996;43632.3455148173;43694.470693525735;9422.246220302393;9647.179989700884;9648.75878103046;9655.903440965605;9657.661981830637;8562.48625082497;8732.956335218316;8734.126587341247;8737.912620873805;11671.824922626167;11518.942405288004;11569.652910412658;11572.71068223297;11574.479148438299;11588.246763960316;11542.076633869312;11610.53557857689;11613.361265130414;11615.709607259801;11623.186376953616;8623.611937462767;11451.94274028633;11564.768704625945;11780.410979450993;11783.764324713458;39515.40726889099;39836.80081599573;39891.60108398908;39894.20211595781;39972.40041399381;3030.7423868971164;3142.9371412571622;3190.090824115139;3241.8703251869897;3256.641769405387;27689.584656230174;29440.55839162414;29780.40439191204;29861.70138298611;30141.69858301411;2702.864856757174;2733.3439974406197;2755.6279902213146;2799.1602519244148;2883.351245969661;467757;470309;472396;472588;472999;66164;67171.32828671749;67188.32811671919;67416.61791983506;67474.66262668655;70902.2909770906;70978.9353159703;70993.64503177554;71116.93324600135;72254;17418.82581174172;17899.73150402745;17904.820951790574;17912.731309030376;17943.282268709365;13657.931710341385;13766.036377453482;13788.172709637454;13794.241316727475;13805.309734513234;16280.43018494347;16607.83392166087;18724.25102995865;18898.52753681169;18904.149313280934;6800.285969973181;6954.5395466951495;7038.085048943604;7055;7069.787906362817;911.7173676160412;991.1426615977127;1079.5519859258477;1086.2016417932814;1120.93834839083;28517.152084145746;29369.706302937124;29927.700722992926;30256.394872102435;30268.697313026587;1087.026916619756;1089.9455027248605;1147.2313549921405;1278.3352656618506;1286.208981476404;13105.737885242243;14629.414823406945;14774;14966;15010.324535395715;615387;623217;623240;623541;623891;56956.43043569594;62180.689096554226;62200.37799621946;62448;62513.68743156346;1362;1395.1280449718722;1537.5233677559768;1547.1104115133844;1605.3739041773952;27382.45235095247;28874.855625721735;29127.708722913347;30178.09465716032;30185.698143019166;49996.50003499918;50037;50502.494975049776;50503.747481262355;50589.24116138187;348282;349431;349882;350524;352196;24017.51964960726;27217.455650886477;27741.445171096868;28086.71913280841;28177;42915.785421072695;43827.78086109549;43831.78084109559;43873.78063109664;44936;36544.81727591345;36676.81661591675;37844;37862.43206351852;38438.423423648106;36614.26771464609;36952.81523592365;37290.440643389826;37743.05642358963;37840.81079594584;37003.62996370002;37501.43747843839;37534.62465375311;37556.248875022895;38273.808630956664;29057.692403842302;32154.51768223525;32657.020289391356;32657.673423265463;33209.6716131352;24928.0028798846;25061.624075638516;25172.24483265505;25243.99024039018;25922.61116083298 02:47:53 INFO - PROCESS | 1665 | |2;jquery.html;394.5600655269375;395.351623337726;397.94826672532565;398.85840526613043;407.6188763506122;14504.347304371302;14508.854911450882;16662;21081.051352689126;21105.894470527644;21165.73005619664;21337;21726.782732172713;22296.773677447734;22487.887560562216;5814.651120932736;5860.326062502813;6089.238845144357;6100.542459315569;6122.785702500413;3940.2316548273097;6057.878842423149;6108.114323423101;6111.908321375183;6114.052321890114;5886.17593536903;6174.073888916676;6175.876482470348;6186.164867742842;6189.907151392733;6116.388361163892;6128.908066379008;6138.9693051534905;6313.179286692701;6725.159355080615;5778.971105144447;5916.437938395846;5945.524358051345;5952.702364881782;5953.434423729739;13543.525976590772;13852.238126903016;14073.859261407357;14090.647733806634;14095.78856317156;2150.505383761728;2169.642009068502;2175.5322605639703;2271.886405679726;2310.560993411263;10149.086582207638;14179.929100354433;14387.633174848374;14664.41342346305;15235.847641523555;1708.4789139312504;1720.664470428267;1725.8533024692915;1733.3846484497947;1833.5324492254524;104112.43831342536;108904;111390;112252.31621525684;113088;9961;11687.707807304885;11714.121440891968;11821.172517923662;11849.763004739856;15000.024998375071;15054.623634409227;15057.171855547831;15058.472953446577;15109.47116850927;8648.481091134552;8957.417767845069;8993.730188094367;9051.502167380857;9060.592273347584;8933.776655583533;8944.731658050268;8963.372563920462;9032.32257580671;9044.728658140266;4010.979945100256;4664.930026049612;4737.715737055788;4763.1426343258545;4806.543378379049;1940.8835469871854;1977.3474753331366;1981.3956743193464;1982.3061928324971;1995.5909038647042;822.617482870468;875.1248751248751;961.5192403798101;962.4706411473661;981.1758123176423;17853.196606152753;20311.492212694506;20526.486837829172;21286.57426851454;21477.89261053716;985.4235272365603;988.9851652225223;993.1359717046216;994.8955359687274;1015.4973288222377;11038.88394182253;11245.666146770007;11267.437404508042;11307.752232187338;11340.489677964511;589891;618811;620294;635983;645649;53988.730056349465;70159;116764;123990.38004809918;125128.1230781539;994.8756405449464;1003.844404117363;1022.7749895023084;1047.7118792332017;1050.2333296693457;18144.546386340447;18224.72662910084;18287.45137645873;18320.541986450444;19156.23375064982;51105.488945110075;52294.73852630713;52617.47382526279;53024.73487632537;54713.72643136759;74375.62812185904;75445.24554754602;75453;78074;78327;8205.876911846446;8234.135415781257;8248.587570621565;8328;8572.528510931708;10631.787364252827;12427.502899883903;12790.04074694383;12924.095313327976;13101.213927164401;9401.952990235006;10559.419231942316;10968.287061340987;11100.278481898651;11232.887671123184;10763.138948884227;10812.297200681931;10890.401027943222;11022.614208502664;11033.282836615916;11064.612738554111;11128.777424451628;11354.545818167511;11376.538308852654;11421.771564568828;8067.193280671886;8080.797980050545;8333.250007499355;8350.540720260444;8356.832863342821;10055.849162262426;11021.338719676845;11254.38100904458;11350.432478375884;11386.94306528462 02:47:53 INFO - PROCESS | 1665 | |3;mootools.html;1384.951526696566;1544.3204989804487;1554.5724925645445;1758.9736153957692;1902.9333973310936;7863.803404914873;8503.107173746752;8752.343574231934;8759.386842920998;8764;8517.95741021294;8760.255378292853;8773.60518776655;8777.473351598892;8787.86818197726;2150.731158605174;2217.1463986365284;2374.4538756086113;2427.6115821468525;2454.202384225125;2454.104251948044;2455.8894849731805;2457.0908763757434;2461.90152393904;2474;2373.7270213925362;2399.5560821248037;2437.9760500589705;2439.731629520751;2442.658027876099;2119.3535971528754;2379.619260918262;2427.9757202427927;2444.1445494077107;2447.889844956981;2421.9273421797375;2424.163986978205;2464.6549483072386;2464.8151388645742;2472.4066224106264;8480.448770829877;8592.011918629312;8618.65525378984;8621.525816080113;8634.395592308541;5327.760250788724;5470.042742520083;5474.671519708791;5486.643368181056;5489.752961116719;3311.1391038330207;3339.7328213742844;3410.8635654573786;3475.1138459692615;3481.808500532495;913.6847787513298;1054.583439541377;1099.2964502718266;1625.5123462961064;1661.1694152923537;11559.768804623944;11677.007454366389;11683.591074312444;11685.591004314809;11730.944215020689;4735.786889589976;4766.746734650727;4813.7352445615825;4865.5864251538305;4882.487338829443;10188.184945204366;10250.282480226313;10260.589576417009;10263.9486802567;10266.743331416774;5428.67427954324;5440.591955603346;5469.097598896137;5471.151971444432;5482.068048431776;4360.106178233455;4530.682852200381;4780.498047704941;4846.903061938741;4928.507149285043;4031.113155105872;4038.9568971861277;4113.773742444194;4143.647789937829;4151.1075118848985;1325;1336.73265346931;1340.6380277325068;1344.6571124363224;1352.4041630875465;912.5847739278612;919.5769945824861;948.0614191950058;948.8576713493031;957.9904200958041;5889.646621202656;5953.523718102541;5988.490978266809;5998.640081595118;6191.473724733448;441.41512495943005;458.749981260214;463.45081078921584;473.290064902646;997.7854761226371;3911.413288006822;3915;3952.9209415812097;3955.92088158241;4013.678905687538;10367.32612380193;10567.207459440573;10607.514947907299;10612.151027917747;10676.359418434919;10552.841707374242;10557.683269501927;10564.260501764802;10570.52432640533;10571.04860562553;8140;8199.836003280021;8232.423730338818;8280.04779450359;8352.791180220422;5741.45456181662;5787.276590426197;5788.189653448437;5973.402659733992;6023.867778498991;9400.435973841591;9609.855852162083;9740.220782337254;9741.977092405195;9750.609975600897;9147.993720690585;9522.666706665234;9539.618415263312;9698;9886.060824221688;6296.33888441707;6409.551331406757;6567.605943643397;6569.704363303758;7475.177730449537;7505.6997720090585;7550.207228240955;7609.429292802951;7957.442978991415;8147.877781833396;7230.530015548972;7340.926590734024;7411.444141689287;7509.324160825552;7527.2849079337375;7182.138143422822;7250.528715633467;7255;7265.273472652692;7274.563526188445;6848.041274221513;6931.341522555349;6932.202796678446;6936.2023367311385;6941.722331106901;4845.345878306383;4882.096812089775;4882.414110306786;4981.850544483671;5050;4887.144749668865;4890.510948905081;4984.302197692398;5010.974945125251;5179.067767801832 02:47:53 INFO - PROCESS | 1665 | |4;prototype.html;314.50465516811016;321.1601661654773;328.761647805341;330.11693719300865;332.4863086531309;6301.968490157548;6306.211723534559;6353.745850165994;6373.9362606373925;6492.05865149553;6119.694015299239;6339.587926784768;6352.491800655948;6374.585651932633;6426.068220108094;4027.65764909983;4502.639788816895;4700.1539722849875;4702.459217190016;4710.670253082286;4669.626429885618;4669.929951050737;4679.789409476582;4685.62514998801;4695.6478264130155;4646.117237724821;4691.179043667362;4691.5073917238715;4698.929516057245;4706.976465117669;3859.1316953685478;4671.5795578397765;4695.6478264130155;4699.013207226487;4708.788104535304;3707.535523468215;3844.711646626486;3940.271049855786;3998.680105591575;4007.2786898358286;6094.664793436359;6130;6141.078838174265;6144.723487443076;6161.32225455204;5513.173024046385;5527.806526771544;5528.391876893538;5533.5849811263915;5561.360443549003;6808.183018037817;6834.82180785932;6843.315668433117;6844.110265665432;6847.075644787939;3750.587435382106;4493.865184044451;4512.526184750587;4517.186906356855;4521.570450807168;6241.28225254097;6724.361185687352;7093.92906070943;7115.679794409212;7215.314545118205;4478.350639157304;4511.661625378109;4526.637868970541;4527.954720452819;4560.771961401917;3898.5126859142606;4640.605548528413;4642.814287428532;4645.883852903637;4655.138799322136;5450.400455949843;5453.945460545423;5463.399026107125;5476.342838859362;5495;6861.245263021064;6876.209235937831;6881.174259088842;6910.2744211858035;6993.0908981832035;1566.6631674189875;1587.8888477806443;1589.0465720567568;1592.283472437408;1593.2352470813842;989.5794287427958;1016.7915577306634;1028.3007554862616;1030.6701855406197;1036.3315661398356;6335.746570137235;6429.45349645276;6444;6454.677266136674;6472.967635161794;7108.822279443055;7124.643767811693;7125.5368401053765;7138.464615153885;7153.535020223669;5336.786528538815;6423.357664233539;6424.293327733945;6435.4208121268375;6437.903431448532;3711.0536813112485;3757.28611563802;3760.398336266239;3762.6049264827348;3764.454154147688;7105.92894071063;7374.778756637309;7403.9629801851725;7476.738314158978;7500.549967001997;7062.717491300393;7334.3032411920785;7363.77908662741;7387.556746595222;7399.48203625741;5756.769729210785;7348.522346047596;7377.446691498159;7432.554046757104;7503.5497870127965;6830.521863469509;6951.7914462566205;7179.928200717926;7187.101612298463;7211.1707153678;6069.484093852072;7043.36609705129;7048.471364647775;7065.399441047363;7084.2915708428745;7508.174100848793;7509.136449308399;7511.136219334847;7520.548767073774;7546.9245307546225;4519.0284088919725;4563.2242518772555;4567.703099298535;4570.520095389937;4582.816687332469;6792.76225332111;7106.964465177641;7109.644517773987;7124.5725256484775;7165.605891676008;6844.931550684629;6912.654367281515;6938.27148149437;6943;6985.1967023790885;6962;6962.582245065312;6966.79099627012;6971;7007.299270072952;6777.423918966943;6783.152105986752;6788.558743681842;6789.864202715819;6793.72825086991;6573.868522629617;6896.827579310558;6898.034275201376;6908.620025898624;6924.272951340036;5524;5671.744771485375;6680.632565208765;6706.396424321834;6791.117154769849 02:47:53 INFO - PROCESS | 1665 | |5;yui.html;589.7720075997466;673.8820706376383;726.2700985509562;754.6415452659984;755.1504557372955;14703.41186352546;14886.25568721565;15065.472708455207;15072.84927150731;15104.471343502979;13880.514182003632;14114.294285285747;14135.293235338218;14136.50522231722;14388.568342949702;728.7412968396222;738.3170567225295;910.2354022621014;913.5204017890627;939.4175611121115;926.4950601921969;935.0602644342426;936.4381371177308;938.1837801113041;938.9530523473833;978.0659470205936;985.3348989432141;985.6599473181741;986.2159583131391;987.8172538080443;760.8516339313836;973.6738192705452;977.8239916814971;978.046404755365;978.6183388478499;723.9900339027081;724.3372314332432;724.7572063358781;725.329070609684;725.742361461684;12863.356832158355;13009.349532523336;13037.15258508194;13093.672658183526;13125.868741312655;12406.25562466255;12440.315782631951;12662.176958497761;12706.809397858948;12748.235105893677;9836.754081147958;10063;10096.394216347042;10205.48972551377;10715.249932504728;3464.116650254169;3466.2201004773974;3500.3524347995703;3507.5615548056494;3619.1314084619767;77762.61118694427;78093.60953195255;78126.6093669528;83118;83359;9453.243740500744;9469.384490008128;9483.857742133874;9553.90446095544;9571.28215383849;18067.638647227246;21132.47168820792;21672.783272167075;21699.457513562287;21742.565148697253;7446.888296675555;7562.243775622504;7671.194524574951;7777.494462859896;7878.960605196938;4051.39229115635;4057.655099316532;4069.918601627951;4076.1694356953208;4332.718373305788;3565.8038807865814;3614.8192590370377;3629.4555816627717;3636.4545318201954;3638.308721342936;434.5307068366157;637.4581605635266;639.3925770518026;645.2063961327593;656.5010591950153;554.3043480432032;554.6644280210414;569.7151424287856;571.1632458448393;579.138071101411;7598.582077985653;7800.9609951951015;7897.091834438998;7899.131095579482;7928.365730741527;481.5208867177181;549.2530158983778;553.6788662575697;553.97784088636;558.974846131925;3625.927481450409;3712.201876596545;3777.7355585109326;3799.601041890617;3807.314683357022;90282;90604.54697726469;91607.54196228976;91850;92090.61864072044;79227;79461.0134746636;79942.60028699819;80858.59570702109;81535.77696334559;69880;71075.93386099213;71124.64437677882;71183.64408177926;72779.63610181915;7472.36484898768;7561.357284630868;7589.772306830804;7636;7729.458937874295;45955.770221150015;47834.28248576204;49758.50724478271;50133.99732005412;51474.48525514697;61444.38555614387;61482;61530;61872.07191892035;64412.677936610016;10294.330868493524;10414.583416663248;10878.673639790819;11132.833007505056;11615.070794336601;12024.819627705416;12071.39643017863;12218.327991960172;12243.265404075784;12351.567695130627;10906.836397454203;11495.080393568662;11602.94198529002;11643.010344120506;11645.41772911368;11206.663800086011;11437.199396042197;11494.137939654727;11525.827112593486;12139.210951288138;11022.559097636005;11243.161667609858;11287.548498059985;11660.591879284184;11714.297142171497;9441.811163776549;9516.476593787409;9699;9748;9958.15355694774;9441.669541566012;9460.905390946002;9475.905240947777;9575.808483830424;9594.66418675343 02:47:53 INFO - PROCESS | 1665 | __end_tp_report 02:47:53 INFO - PROCESS | 1665 | __start_cc_report 02:47:53 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1231 02:47:53 INFO - PROCESS | 1665 | __end_cc_report 02:47:53 INFO - PROCESS | 1665 | __startTimestamp1465206473050__endTimestamp 02:47:53 INFO - PROCESS | 1665 | 02:47:53 INFO - PROCESS | 1665 | ------- Summary: start ------- 02:47:53 INFO - PROCESS | 1665 | Number of tests: 6 02:47:53 INFO - PROCESS | 1665 | 02:47:53 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5522.52 Median:5644.35 stddev:650.10 (11.5%) stddev-sans-first:627.43 02:47:53 INFO - PROCESS | 1665 | Values: 3261.6 3325.4 3331.4 3358.5 3378.9 5447.3 5451.9 5455.4 5459.4 5466.9 5474.8 5525.6 5532.6 5534.2 5555.8 4912.4 4951.2 5024.7 5059.8 5113.4 4964.0 5034.2 5050.0 5053.8 5063.7 4961.2 5009.7 5013.8 5034.0 5055.5 3872.9 5053.5 5067.0 5070.4 5088.4 4900.5 4901.6 4994.3 4999.3 5061.2 5290.7 5291.6 5318.4 5330.7 5379.2 5881.7 5953.3 5962.4 5973.5 6007.6 4723.3 5984.7 6094.9 6103.8 6104.1 5565.7 5603.7 5616.6 5617.3 5652.5 6240.9 6246.0 6257.7 6259.7 6288.7 4901.6 5780.4 5867.3 5892.4 5938.0 6106.1 6108.3 6120.5 6139.3 6151.1 5593.2 5679.1 6021.8 6024.0 6033.4 5863.9 5956.2 5970.9 5993.2 6064.6 4745.8 4764.9 4786.4 4856.0 4857.6 4258.0 4300.4 4302.1 4309.6 4334.9 5528.4 5540.8 5545.4 5563.3 5570.2 5947.6 6011.1 6036.7 6058.7 6069.1 5472.3 5495.8 5503.3 5510.4 5512.8 5183.2 5203.9 5220.7 5232.5 5233.7 5229.0 6228.3 6283.6 6354.7 6384.1 6052.8 6088.3 6095.9 6099.2 6104.0 6328.9 6329.8 6338.1 6343.4 6349.0 5750.0 5844.4 5907.8 5995.3 6020.4 5018.0 6134.6 6136.4 6147.9 6150.9 5916.4 5926.3 5945.0 5946.4 5952.7 5751.2 5783.3 5851.2 5854.0 5888.8 4519.3 4819.8 5799.9 5807.7 5822.6 5934.5 5967.3 5968.9 5973.0 5995.5 5589.3 5644.4 5680.7 5686.6 5696.5 02:47:53 INFO - PROCESS | 1665 | 02:47:53 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:63227.59 Median:348856.50 stddev:131696.95 (37.8%) stddev-sans-first:131982.18 02:47:53 INFO - PROCESS | 1665 | Values: 499.8 500.1 528.7 568.7 584.0 31234.4 31822.0 39698.8 40871.0 40996.4 42995.8 43556.7 43596.8 43632.3 43694.5 9422.2 9647.2 9648.8 9655.9 9657.7 8562.5 8733.0 8734.1 8737.9 11671.8 11518.9 11569.7 11572.7 11574.5 11588.2 11542.1 11610.5 11613.4 11615.7 11623.2 8623.6 11451.9 11564.8 11780.4 11783.8 39515.4 39836.8 39891.6 39894.2 39972.4 3030.7 3142.9 3190.1 3241.9 3256.6 27689.6 29440.6 29780.4 29861.7 30141.7 2702.9 2733.3 2755.6 2799.2 2883.4 467757.0 470309.0 472396.0 472588.0 472999.0 66164.0 67171.3 67188.3 67416.6 67474.7 70902.3 70978.9 70993.6 71116.9 72254.0 17418.8 17899.7 17904.8 17912.7 17943.3 13657.9 13766.0 13788.2 13794.2 13805.3 16280.4 16607.8 18724.3 18898.5 18904.1 6800.3 6954.5 7038.1 7055.0 7069.8 911.7 991.1 1079.6 1086.2 1120.9 28517.2 29369.7 29927.7 30256.4 30268.7 1087.0 1089.9 1147.2 1278.3 1286.2 13105.7 14629.4 14774.0 14966.0 15010.3 615387.0 623217.0 623240.0 623541.0 623891.0 56956.4 62180.7 62200.4 62448.0 62513.7 1362.0 1395.1 1537.5 1547.1 1605.4 27382.5 28874.9 29127.7 30178.1 30185.7 49996.5 50037.0 50502.5 50503.7 50589.2 348282.0 349431.0 349882.0 350524.0 352196.0 24017.5 27217.5 27741.4 28086.7 28177.0 42915.8 43827.8 43831.8 43873.8 44936.0 36544.8 36676.8 37844.0 37862.4 38438.4 36614.3 36952.8 37290.4 37743.1 37840.8 37003.6 37501.4 37534.6 37556.2 38273.8 29057.7 32154.5 32657.0 32657.7 33209.7 24928.0 25061.6 25172.2 25244.0 25922.6 02:47:53 INFO - PROCESS | 1665 | 02:47:53 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:34365.71 Median:11951.16 stddev:102981.13 (861.7%) stddev-sans-first:103238.43 02:47:53 INFO - PROCESS | 1665 | Values: 394.6 395.4 397.9 398.9 407.6 14504.3 14508.9 16662.0 21081.1 21105.9 21165.7 21337.0 21726.8 22296.8 22487.9 5814.7 5860.3 6089.2 6100.5 6122.8 3940.2 6057.9 6108.1 6111.9 6114.1 5886.2 6174.1 6175.9 6186.2 6189.9 6116.4 6128.9 6139.0 6313.2 6725.2 5779.0 5916.4 5945.5 5952.7 5953.4 13543.5 13852.2 14073.9 14090.6 14095.8 2150.5 2169.6 2175.5 2271.9 2310.6 10149.1 14179.9 14387.6 14664.4 15235.8 1708.5 1720.7 1725.9 1733.4 1833.5 104112.4 108904.0 111390.0 112252.3 113088.0 9961.0 11687.7 11714.1 11821.2 11849.8 15000.0 15054.6 15057.2 15058.5 15109.5 8648.5 8957.4 8993.7 9051.5 9060.6 8933.8 8944.7 8963.4 9032.3 9044.7 4011.0 4664.9 4737.7 4763.1 4806.5 1940.9 1977.3 1981.4 1982.3 1995.6 822.6 875.1 961.5 962.5 981.2 17853.2 20311.5 20526.5 21286.6 21477.9 985.4 989.0 993.1 994.9 1015.5 11038.9 11245.7 11267.4 11307.8 11340.5 589891.0 618811.0 620294.0 635983.0 645649.0 53988.7 70159.0 116764.0 123990.4 125128.1 994.9 1003.8 1022.8 1047.7 1050.2 18144.5 18224.7 18287.5 18320.5 19156.2 51105.5 52294.7 52617.5 53024.7 54713.7 74375.6 75445.2 75453.0 78074.0 78327.0 8205.9 8234.1 8248.6 8328.0 8572.5 10631.8 12427.5 12790.0 12924.1 13101.2 9402.0 10559.4 10968.3 11100.3 11232.9 10763.1 10812.3 10890.4 11022.6 11033.3 11064.6 11128.8 11354.5 11376.5 11421.8 8067.2 8080.8 8333.3 8350.5 8356.8 10055.8 11021.3 11254.4 11350.4 11386.9 02:47:53 INFO - PROCESS | 1665 | 02:47:53 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5659.03 Median:4888.83 stddev:3132.67 (64.1%) stddev-sans-first:3124.99 02:47:53 INFO - PROCESS | 1665 | Values: 1385.0 1544.3 1554.6 1759.0 1902.9 7863.8 8503.1 8752.3 8759.4 8764.0 8518.0 8760.3 8773.6 8777.5 8787.9 2150.7 2217.1 2374.5 2427.6 2454.2 2454.1 2455.9 2457.1 2461.9 2474.0 2373.7 2399.6 2438.0 2439.7 2442.7 2119.4 2379.6 2428.0 2444.1 2447.9 2421.9 2424.2 2464.7 2464.8 2472.4 8480.4 8592.0 8618.7 8621.5 8634.4 5327.8 5470.0 5474.7 5486.6 5489.8 3311.1 3339.7 3410.9 3475.1 3481.8 913.7 1054.6 1099.3 1625.5 1661.2 11559.8 11677.0 11683.6 11685.6 11730.9 4735.8 4766.7 4813.7 4865.6 4882.5 10188.2 10250.3 10260.6 10263.9 10266.7 5428.7 5440.6 5469.1 5471.2 5482.1 4360.1 4530.7 4780.5 4846.9 4928.5 4031.1 4039.0 4113.8 4143.6 4151.1 1325.0 1336.7 1340.6 1344.7 1352.4 912.6 919.6 948.1 948.9 958.0 5889.6 5953.5 5988.5 5998.6 6191.5 441.4 458.7 463.5 473.3 997.8 3911.4 3915.0 3952.9 3955.9 4013.7 10367.3 10567.2 10607.5 10612.2 10676.4 10552.8 10557.7 10564.3 10570.5 10571.0 8140.0 8199.8 8232.4 8280.0 8352.8 5741.5 5787.3 5788.2 5973.4 6023.9 9400.4 9609.9 9740.2 9742.0 9750.6 9148.0 9522.7 9539.6 9698.0 9886.1 6296.3 6409.6 6567.6 6569.7 7475.2 7505.7 7550.2 7609.4 7957.4 8147.9 7230.5 7340.9 7411.4 7509.3 7527.3 7182.1 7250.5 7255.0 7265.3 7274.6 6848.0 6931.3 6932.2 6936.2 6941.7 4845.3 4882.1 4882.4 4981.9 5050.0 4887.1 4890.5 4984.3 5011.0 5179.1 02:47:53 INFO - PROCESS | 1665 | 02:47:53 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:5589.79 Median:6353.12 stddev:1803.81 (28.4%) stddev-sans-first:1764.88 02:47:53 INFO - PROCESS | 1665 | Values: 314.5 321.2 328.8 330.1 332.5 6302.0 6306.2 6353.7 6373.9 6492.1 6119.7 6339.6 6352.5 6374.6 6426.1 4027.7 4502.6 4700.2 4702.5 4710.7 4669.6 4669.9 4679.8 4685.6 4695.6 4646.1 4691.2 4691.5 4698.9 4707.0 3859.1 4671.6 4695.6 4699.0 4708.8 3707.5 3844.7 3940.3 3998.7 4007.3 6094.7 6130.0 6141.1 6144.7 6161.3 5513.2 5527.8 5528.4 5533.6 5561.4 6808.2 6834.8 6843.3 6844.1 6847.1 3750.6 4493.9 4512.5 4517.2 4521.6 6241.3 6724.4 7093.9 7115.7 7215.3 4478.4 4511.7 4526.6 4528.0 4560.8 3898.5 4640.6 4642.8 4645.9 4655.1 5450.4 5453.9 5463.4 5476.3 5495.0 6861.2 6876.2 6881.2 6910.3 6993.1 1566.7 1587.9 1589.0 1592.3 1593.2 989.6 1016.8 1028.3 1030.7 1036.3 6335.7 6429.5 6444.0 6454.7 6473.0 7108.8 7124.6 7125.5 7138.5 7153.5 5336.8 6423.4 6424.3 6435.4 6437.9 3711.1 3757.3 3760.4 3762.6 3764.5 7105.9 7374.8 7404.0 7476.7 7500.5 7062.7 7334.3 7363.8 7387.6 7399.5 5756.8 7348.5 7377.4 7432.6 7503.5 6830.5 6951.8 7179.9 7187.1 7211.2 6069.5 7043.4 7048.5 7065.4 7084.3 7508.2 7509.1 7511.1 7520.5 7546.9 4519.0 4563.2 4567.7 4570.5 4582.8 6792.8 7107.0 7109.6 7124.6 7165.6 6844.9 6912.7 6938.3 6943.0 6985.2 6962.0 6962.6 6966.8 6971.0 7007.3 6777.4 6783.2 6788.6 6789.9 6793.7 6573.9 6896.8 6898.0 6908.6 6924.3 5524.0 5671.7 6680.6 6706.4 6791.1 02:47:53 INFO - PROCESS | 1665 | 02:47:53 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:18077.51 Median:31017.08 stddev:25476.37 (82.1%) stddev-sans-first:25513.99 02:47:53 INFO - PROCESS | 1665 | Values: 589.8 673.9 726.3 754.6 755.2 14703.4 14886.3 15065.5 15072.8 15104.5 13880.5 14114.3 14135.3 14136.5 14388.6 728.7 738.3 910.2 913.5 939.4 926.5 935.1 936.4 938.2 939.0 978.1 985.3 985.7 986.2 987.8 760.9 973.7 977.8 978.0 978.6 724.0 724.3 724.8 725.3 725.7 12863.4 13009.3 13037.2 13093.7 13125.9 12406.3 12440.3 12662.2 12706.8 12748.2 9836.8 10063.0 10096.4 10205.5 10715.2 3464.1 3466.2 3500.4 3507.6 3619.1 77762.6 78093.6 78126.6 83118.0 83359.0 9453.2 9469.4 9483.9 9553.9 9571.3 18067.6 21132.5 21672.8 21699.5 21742.6 7446.9 7562.2 7671.2 7777.5 7879.0 4051.4 4057.7 4069.9 4076.2 4332.7 3565.8 3614.8 3629.5 3636.5 3638.3 434.5 637.5 639.4 645.2 656.5 554.3 554.7 569.7 571.2 579.1 7598.6 7801.0 7897.1 7899.1 7928.4 481.5 549.3 553.7 554.0 559.0 3625.9 3712.2 3777.7 3799.6 3807.3 90282.0 90604.5 91607.5 91850.0 92090.6 79227.0 79461.0 79942.6 80858.6 81535.8 69880.0 71075.9 71124.6 71183.6 72779.6 7472.4 7561.4 7589.8 7636.0 7729.5 45955.8 47834.3 49758.5 50134.0 51474.5 61444.4 61482.0 61530.0 61872.1 64412.7 10294.3 10414.6 10878.7 11132.8 11615.1 12024.8 12071.4 12218.3 12243.3 12351.6 10906.8 11495.1 11602.9 11643.0 11645.4 11206.7 11437.2 11494.1 11525.8 12139.2 11022.6 11243.2 11287.5 11660.6 11714.3 9441.8 9516.5 9699.0 9748.0 9958.2 9441.7 9460.9 9475.9 9575.8 9594.7 02:47:53 INFO - PROCESS | 1665 | -------- Summary: end -------- 02:47:53 INFO - PROCESS | 1665 | 02:47:53 INFO - TEST-INFO | 1665: exit 0 02:47:53 INFO - TEST-OK | dromaeo_css | took 1114942ms 02:47:53 INFO - TEST-START | kraken 02:47:53 INFO - operating with platform_type : mac_ 02:47:53 INFO - Initialising browser for kraken test... 02:47:53 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpU2HEZO/profile http://localhost:49273/getInfo.html) 02:47:55 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 02:47:55 INFO - PROCESS | 1673 | colorDepth:24 02:47:55 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 02:47:55 INFO - PROCESS | 1673 | __metrics 02:47:55 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" 02:47:55 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:47:56 INFO - TEST-INFO | 1673: exit 0 02:47:56 INFO - Browser initialized. 02:47:56 INFO - Running cycle 1/1 for kraken test... 02:47:56 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpU2HEZO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:48:08 INFO - PROCESS | 1675 | RSS: Main: 211288064 02:48:08 INFO - PROCESS | 1675 | 02:48:11 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 02:48:12 INFO - PROCESS | 1675 | RSS: Main: 272039936 02:48:12 INFO - PROCESS | 1675 | 02:48:14 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 02:48:15 INFO - PROCESS | 1675 | RSS: Main: 285921280 02:48:15 INFO - PROCESS | 1675 | 02:48:18 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 02:48:19 INFO - PROCESS | 1675 | RSS: Main: 374370304 02:48:19 INFO - PROCESS | 1675 | 02:48:21 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 02:48:21 INFO - PROCESS | 1675 | RSS: Main: 279334912 02:48:21 INFO - PROCESS | 1675 | 02:48:23 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:48:24 INFO - PROCESS | 1675 | RSS: Main: 280920064 02:48:24 INFO - PROCESS | 1675 | 02:48:27 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 02:48:28 INFO - PROCESS | 1675 | RSS: Main: 318636032 02:48:28 INFO - PROCESS | 1675 | 02:48:30 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 02:48:31 INFO - PROCESS | 1675 | RSS: Main: 319164416 02:48:31 INFO - PROCESS | 1675 | 02:48:34 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 02:48:34 INFO - PROCESS | 1675 | RSS: Main: 316641280 02:48:34 INFO - PROCESS | 1675 | 02:48:36 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:48:37 INFO - PROCESS | 1675 | RSS: Main: 309460992 02:48:37 INFO - PROCESS | 1675 | 02:48:38 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:48:39 INFO - PROCESS | 1675 | RSS: Main: 316985344 02:48:39 INFO - PROCESS | 1675 | 02:48:41 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:48:42 INFO - PROCESS | 1675 | RSS: Main: 319143936 02:48:42 INFO - PROCESS | 1675 | 02:48:45 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:48:45 INFO - PROCESS | 1675 | RSS: Main: 320929792 02:48:45 INFO - PROCESS | 1675 | 02:48:48 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:48:49 INFO - PROCESS | 1675 | RSS: Main: 317480960 02:48:49 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 02:48:51 INFO - PROCESS | 1675 | RSS: Main: 382468096 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | __start_tp_report 02:48:51 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 02:48:51 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 02:48:51 INFO - PROCESS | 1675 | |i|pagename|runs| 02:48:51 INFO - PROCESS | 1675 | |0;ai-astar;113;114;117;112;114;113;112;113;114;134 02:48:51 INFO - PROCESS | 1675 | |1;audio-beat-detection;154;123;160;157;119;142;137;130;168;138 02:48:51 INFO - PROCESS | 1675 | |2;audio-dft;184;188;186;186;184;185;184;184;186;184 02:48:51 INFO - PROCESS | 1675 | |3;audio-fft;72;78;73;152;56;55;57;67;133;61 02:48:51 INFO - PROCESS | 1675 | |4;audio-oscillator;91;92;140;77;105;129;134;80;75;141 02:48:51 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;94;91;95;91;94;92;94;90;94;87 02:48:51 INFO - PROCESS | 1675 | |6;imaging-darkroom;96;96;96;97;96;95;95;95;96;96 02:48:51 INFO - PROCESS | 1675 | |7;imaging-desaturate;84;84;86;86;84;84;84;83;87;84 02:48:51 INFO - PROCESS | 1675 | |8;json-parse-financial;60;64;64;62;63;64;65;79;61;58 02:48:51 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;63;66;66;75;72;73;76;69;66;67 02:48:51 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;82;81;79;96;103;76;80;81;79;85 02:48:51 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;130;131;130;130;129;128;128;130;181;161 02:48:51 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;180;169;166;166;165;168;171;161;170;162 02:48:51 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;67;70;68;67;69;73;94;94;69;62 02:48:51 INFO - PROCESS | 1675 | __end_tp_report 02:48:51 INFO - PROCESS | 1675 | __start_cc_report 02:48:51 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,2084 02:48:51 INFO - PROCESS | 1675 | __end_cc_report 02:48:51 INFO - PROCESS | 1675 | __startTimestamp1465206531339__endTimestamp 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | ------- Summary: start ------- 02:48:51 INFO - PROCESS | 1675 | Number of tests: 14 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:115.60 Median:114.00 stddev:6.62 (5.8%) stddev-sans-first:6.95 02:48:51 INFO - PROCESS | 1675 | Values: 113.0 114.0 117.0 112.0 114.0 113.0 112.0 113.0 114.0 134.0 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:142.80 Median:148.00 stddev:16.46 (11.1%) stddev-sans-first:16.95 02:48:51 INFO - PROCESS | 1675 | Values: 154.0 123.0 160.0 157.0 119.0 142.0 137.0 130.0 168.0 138.0 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:185.10 Median:185.50 stddev:1.37 (0.7%) stddev-sans-first:1.39 02:48:51 INFO - PROCESS | 1675 | Values: 184.0 188.0 186.0 186.0 184.0 185.0 184.0 184.0 186.0 184.0 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:80.40 Median:64.00 stddev:33.94 (53.0%) stddev-sans-first:35.86 02:48:51 INFO - PROCESS | 1675 | Values: 72.0 78.0 73.0 152.0 56.0 55.0 57.0 67.0 133.0 61.0 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:106.40 Median:76.00 stddev:27.06 (35.6%) stddev-sans-first:28.13 02:48:51 INFO - PROCESS | 1675 | Values: 91.0 92.0 140.0 77.0 105.0 129.0 134.0 80.0 75.0 141.0 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:92.20 Median:94.00 stddev:2.49 (2.6%) stddev-sans-first:2.55 02:48:51 INFO - PROCESS | 1675 | Values: 94.0 91.0 95.0 91.0 94.0 92.0 94.0 90.0 94.0 87.0 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 02:48:51 INFO - PROCESS | 1675 | Values: 96.0 96.0 96.0 97.0 96.0 95.0 95.0 95.0 96.0 96.0 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:84.00 stddev:1.26 (1.5%) stddev-sans-first:1.32 02:48:51 INFO - PROCESS | 1675 | Values: 84.0 84.0 86.0 86.0 84.0 84.0 84.0 83.0 87.0 84.0 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.00 stddev:5.70 (8.9%) stddev-sans-first:5.85 02:48:51 INFO - PROCESS | 1675 | Values: 60.0 64.0 64.0 62.0 63.0 64.0 65.0 79.0 61.0 58.0 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:69.30 Median:70.50 stddev:4.42 (6.3%) stddev-sans-first:4.06 02:48:51 INFO - PROCESS | 1675 | Values: 63.0 66.0 66.0 75.0 72.0 73.0 76.0 69.0 66.0 67.0 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:81.00 stddev:8.55 (10.6%) stddev-sans-first:9.03 02:48:51 INFO - PROCESS | 1675 | Values: 82.0 81.0 79.0 96.0 103.0 76.0 80.0 81.0 79.0 85.0 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:137.80 Median:130.00 stddev:18.15 (14.0%) stddev-sans-first:19.03 02:48:51 INFO - PROCESS | 1675 | Values: 130.0 131.0 130.0 130.0 129.0 128.0 128.0 130.0 181.0 161.0 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:168.50 stddev:5.37 (3.2%) stddev-sans-first:3.43 02:48:51 INFO - PROCESS | 1675 | Values: 180.0 169.0 166.0 166.0 165.0 168.0 171.0 161.0 170.0 162.0 02:48:51 INFO - PROCESS | 1675 | 02:48:51 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:69.50 stddev:11.26 (16.2%) stddev-sans-first:11.70 02:48:51 INFO - PROCESS | 1675 | Values: 67.0 70.0 68.0 67.0 69.0 73.0 94.0 94.0 69.0 62.0 02:48:51 INFO - PROCESS | 1675 | -------- Summary: end -------- 02:48:51 INFO - PROCESS | 1675 | 02:48:52 INFO - TEST-INFO | 1675: exit 0 02:48:52 INFO - TEST-OK | kraken | took 58586ms 02:48:52 INFO - SUITE-END | took 1173s 02:48:52 INFO - Completed test suite (00:19:33) 02:48:52 INFO - javascript benchmark 02:48:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3261.62491313499, 3325.4014277430083, 3331.400347937371, 3358.546596209511, 3378.949315760264, 5447.319085114365, 5451.863703407412, 5455.427180146082, 5459.426760190182, 5466.9179962300495, 5474.835754927359, 5525.640833345829, 5532.640378375413, 5534.197541356501, 5555.805546805863, 4912.38595175603, 4951.183054795954, 5024.673396229234, 5059.822906198284, 5113.437521872591, 4963.950360496403, 5034.244863270502, 5049.9747501262445, 5053.77258023388, 5063.670861393997, 4961.156603377434, 5009.674371165881, 5013.849584512453, 5034.0183664185315, 5055.494450554934, 3872.9419058714, 5053.545180933734, 5067.037262920034, 5070.442251352349, 5088.414832294259, 4900.485449027837, 4901.5588597026435, 4994.300797888299, 4999.300097986248, 5061.190209566452, 5290.709011004393, 5291.629585928987, 5318.388385335697, 5330.653507521999, 5379.193121031876, 5881.70591470429, 5953.2856057272975, 5962.403759624046, 5973.462388385023, 6007.579469437142, 4723.338732577442, 5984.73068711909, 6094.939050609526, 6103.786367477117, 6104.053871649901, 5565.749541270652, 5603.691796951207, 5616.5787565932305, 5617.297837770278, 5652.463016013472, 6240.937590624127, 6246.0, 6257.7184026718, 6259.7496100156395, 6288.685565721696, 4901.632377571696, 5780.421957804186, 5867.295924489089, 5892.410758924159, 5938.020226662638, 6106.096658337741, 6108.297545782292, 6120.540959428061, 6139.324674285915, 6151.0773383992755, 5593.188987596775, 5679.119736440858, 6021.849453763691, 6024.036154215392, 6033.396660333931, 5863.882722345615, 5956.16613674086, 5970.850728731816, 5993.160957465958, 6064.575479716377, 4745.786439610226, 4764.928526072112, 4786.44955830084, 4855.975720121377, 4857.635677324215, 4257.957420425756, 4300.440942677432, 4302.118065796504, 4309.59058889405, 4334.869953901388, 5528.391876893538, 5540.7783688652, 5545.362283337387, 5563.304586926634, 5570.248016517799, 5947.6431414115295, 6011.098335249748, 6036.6679832609625, 6058.727357268934, 6069.119977603223, 5472.26124473199, 5495.780168793283, 5503.3120859892515, 5510.366307874566, 5512.751926163332, 5183.248428977777, 5203.947960520422, 5220.660657057279, 5232.502912223333, 5233.685978841282, 5228.9738551307, 6228.314885362606, 6283.622982621057, 6354.650494222862, 6384.106225128486, 6052.788152414821, 6088.26940767093, 6095.908561371494, 6099.207103076572, 6103.969480152571, 6328.936710632835, 6329.778457753957, 6338.144350512806, 6343.397377249154, 6349.0, 5749.9712501437225, 5844.4155584442915, 5907.793227237026, 5995.280566331893, 6020.367861374668, 5017.974910125426, 6134.601250918676, 6136.386361363828, 6147.938520614736, 6150.907736383869, 5916.408359164049, 5926.348101708895, 5945.019071853096, 5946.405359464019, 5952.702364881825, 5751.223584816164, 5783.334916484658, 5851.239338885917, 5853.970730146322, 5888.793892213752, 4519.344695019269, 4819.783109759999, 5799.884002320015, 5807.738651760766, 5822.5633077518505, 5934.465898069022, 5967.313758917607, 5968.850778730566, 5972.970135149297, 5995.46040856325, 5589.329280486205, 5644.3508996465935, 5680.71596420169, 5686.630369026, 5696.544276457957], "value": 5483.13283215754, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.80507602035203, 500.1272779000642, 528.719778517386, 568.6701713006456, 584.0305093544716, 31234.375312493765, 31822.0, 39698.801505992466, 40871.0, 40996.38505422421, 42995.78502107493, 43556.69329920108, 43596.78201608996, 43632.3455148173, 43694.470693525735, 9422.246220302393, 9647.179989700884, 9648.75878103046, 9655.903440965605, 9657.661981830637, 8562.48625082497, 8732.956335218316, 8734.126587341247, 8737.912620873805, 11671.824922626167, 11518.942405288004, 11569.652910412658, 11572.71068223297, 11574.479148438299, 11588.246763960316, 11542.076633869312, 11610.53557857689, 11613.361265130414, 11615.709607259801, 11623.186376953616, 8623.611937462767, 11451.94274028633, 11564.768704625945, 11780.410979450993, 11783.764324713458, 39515.40726889099, 39836.80081599573, 39891.60108398908, 39894.20211595781, 39972.40041399381, 3030.7423868971164, 3142.9371412571622, 3190.090824115139, 3241.8703251869897, 3256.641769405387, 27689.584656230174, 29440.55839162414, 29780.40439191204, 29861.70138298611, 30141.69858301411, 2702.864856757174, 2733.3439974406197, 2755.6279902213146, 2799.1602519244148, 2883.351245969661, 467757.0, 470309.0, 472396.0, 472588.0, 472999.0, 66164.0, 67171.32828671749, 67188.32811671919, 67416.61791983506, 67474.66262668655, 70902.2909770906, 70978.9353159703, 70993.64503177554, 71116.93324600135, 72254.0, 17418.82581174172, 17899.73150402745, 17904.820951790574, 17912.731309030376, 17943.282268709365, 13657.931710341385, 13766.036377453482, 13788.172709637454, 13794.241316727475, 13805.309734513234, 16280.43018494347, 16607.83392166087, 18724.25102995865, 18898.52753681169, 18904.149313280934, 6800.285969973181, 6954.5395466951495, 7038.085048943604, 7055.0, 7069.787906362817, 911.7173676160412, 991.1426615977127, 1079.5519859258477, 1086.2016417932814, 1120.93834839083, 28517.152084145746, 29369.706302937124, 29927.700722992926, 30256.394872102435, 30268.697313026587, 1087.026916619756, 1089.9455027248605, 1147.2313549921405, 1278.3352656618506, 1286.208981476404, 13105.737885242243, 14629.414823406945, 14774.0, 14966.0, 15010.324535395715, 615387.0, 623217.0, 623240.0, 623541.0, 623891.0, 56956.43043569594, 62180.689096554226, 62200.37799621946, 62448.0, 62513.68743156346, 1362.0, 1395.1280449718722, 1537.5233677559768, 1547.1104115133844, 1605.3739041773952, 27382.45235095247, 28874.855625721735, 29127.708722913347, 30178.09465716032, 30185.698143019166, 49996.50003499918, 50037.0, 50502.494975049776, 50503.747481262355, 50589.24116138187, 348282.0, 349431.0, 349882.0, 350524.0, 352196.0, 24017.51964960726, 27217.455650886477, 27741.445171096868, 28086.71913280841, 28177.0, 42915.785421072695, 43827.78086109549, 43831.78084109559, 43873.78063109664, 44936.0, 36544.81727591345, 36676.81661591675, 37844.0, 37862.43206351852, 38438.423423648106, 36614.26771464609, 36952.81523592365, 37290.440643389826, 37743.05642358963, 37840.81079594584, 37003.62996370002, 37501.43747843839, 37534.62465375311, 37556.248875022895, 38273.808630956664, 29057.692403842302, 32154.51768223525, 32657.020289391356, 32657.673423265463, 33209.6716131352, 24928.0028798846, 25061.624075638516, 25172.24483265505, 25243.99024039018, 25922.61116083298], "value": 19948.108329144732, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.5600655269375, 395.351623337726, 397.94826672532565, 398.85840526613043, 407.6188763506122, 14504.347304371302, 14508.854911450882, 16662.0, 21081.051352689126, 21105.894470527644, 21165.73005619664, 21337.0, 21726.782732172713, 22296.773677447734, 22487.887560562216, 5814.651120932736, 5860.326062502813, 6089.238845144357, 6100.542459315569, 6122.785702500413, 3940.2316548273097, 6057.878842423149, 6108.114323423101, 6111.908321375183, 6114.052321890114, 5886.17593536903, 6174.073888916676, 6175.876482470348, 6186.164867742842, 6189.907151392733, 6116.388361163892, 6128.908066379008, 6138.9693051534905, 6313.179286692701, 6725.159355080615, 5778.971105144447, 5916.437938395846, 5945.524358051345, 5952.702364881782, 5953.434423729739, 13543.525976590772, 13852.238126903016, 14073.859261407357, 14090.647733806634, 14095.78856317156, 2150.505383761728, 2169.642009068502, 2175.5322605639703, 2271.886405679726, 2310.560993411263, 10149.086582207638, 14179.929100354433, 14387.633174848374, 14664.41342346305, 15235.847641523555, 1708.4789139312504, 1720.664470428267, 1725.8533024692915, 1733.3846484497947, 1833.5324492254524, 104112.43831342536, 108904.0, 111390.0, 112252.31621525684, 113088.0, 9961.0, 11687.707807304885, 11714.121440891968, 11821.172517923662, 11849.763004739856, 15000.024998375071, 15054.623634409227, 15057.171855547831, 15058.472953446577, 15109.47116850927, 8648.481091134552, 8957.417767845069, 8993.730188094367, 9051.502167380857, 9060.592273347584, 8933.776655583533, 8944.731658050268, 8963.372563920462, 9032.32257580671, 9044.728658140266, 4010.979945100256, 4664.930026049612, 4737.715737055788, 4763.1426343258545, 4806.543378379049, 1940.8835469871854, 1977.3474753331366, 1981.3956743193464, 1982.3061928324971, 1995.5909038647042, 822.617482870468, 875.1248751248751, 961.5192403798101, 962.4706411473661, 981.1758123176423, 17853.196606152753, 20311.492212694506, 20526.486837829172, 21286.57426851454, 21477.89261053716, 985.4235272365603, 988.9851652225223, 993.1359717046216, 994.8955359687274, 1015.4973288222377, 11038.88394182253, 11245.666146770007, 11267.437404508042, 11307.752232187338, 11340.489677964511, 589891.0, 618811.0, 620294.0, 635983.0, 645649.0, 53988.730056349465, 70159.0, 116764.0, 123990.38004809918, 125128.1230781539, 994.8756405449464, 1003.844404117363, 1022.7749895023084, 1047.7118792332017, 1050.2333296693457, 18144.546386340447, 18224.72662910084, 18287.45137645873, 18320.541986450444, 19156.23375064982, 51105.488945110075, 52294.73852630713, 52617.47382526279, 53024.73487632537, 54713.72643136759, 74375.62812185904, 75445.24554754602, 75453.0, 78074.0, 78327.0, 8205.876911846446, 8234.135415781257, 8248.587570621565, 8328.0, 8572.528510931708, 10631.787364252827, 12427.502899883903, 12790.04074694383, 12924.095313327976, 13101.213927164401, 9401.952990235006, 10559.419231942316, 10968.287061340987, 11100.278481898651, 11232.887671123184, 10763.138948884227, 10812.297200681931, 10890.401027943222, 11022.614208502664, 11033.282836615916, 11064.612738554111, 11128.777424451628, 11354.545818167511, 11376.538308852654, 11421.771564568828, 8067.193280671886, 8080.797980050545, 8333.250007499355, 8350.540720260444, 8356.832863342821, 10055.849162262426, 11021.338719676845, 11254.38100904458, 11350.432478375884, 11386.94306528462], "value": 9474.297990569105, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.951526696566, 1544.3204989804487, 1554.5724925645445, 1758.9736153957692, 1902.9333973310936, 7863.803404914873, 8503.107173746752, 8752.343574231934, 8759.386842920998, 8764.0, 8517.95741021294, 8760.255378292853, 8773.60518776655, 8777.473351598892, 8787.86818197726, 2150.731158605174, 2217.1463986365284, 2374.4538756086113, 2427.6115821468525, 2454.202384225125, 2454.104251948044, 2455.8894849731805, 2457.0908763757434, 2461.90152393904, 2474.0, 2373.7270213925362, 2399.5560821248037, 2437.9760500589705, 2439.731629520751, 2442.658027876099, 2119.3535971528754, 2379.619260918262, 2427.9757202427927, 2444.1445494077107, 2447.889844956981, 2421.9273421797375, 2424.163986978205, 2464.6549483072386, 2464.8151388645742, 2472.4066224106264, 8480.448770829877, 8592.011918629312, 8618.65525378984, 8621.525816080113, 8634.395592308541, 5327.760250788724, 5470.042742520083, 5474.671519708791, 5486.643368181056, 5489.752961116719, 3311.1391038330207, 3339.7328213742844, 3410.8635654573786, 3475.1138459692615, 3481.808500532495, 913.6847787513298, 1054.583439541377, 1099.2964502718266, 1625.5123462961064, 1661.1694152923537, 11559.768804623944, 11677.007454366389, 11683.591074312444, 11685.591004314809, 11730.944215020689, 4735.786889589976, 4766.746734650727, 4813.7352445615825, 4865.5864251538305, 4882.487338829443, 10188.184945204366, 10250.282480226313, 10260.589576417009, 10263.9486802567, 10266.743331416774, 5428.67427954324, 5440.591955603346, 5469.097598896137, 5471.151971444432, 5482.068048431776, 4360.106178233455, 4530.682852200381, 4780.498047704941, 4846.903061938741, 4928.507149285043, 4031.113155105872, 4038.9568971861277, 4113.773742444194, 4143.647789937829, 4151.1075118848985, 1325.0, 1336.73265346931, 1340.6380277325068, 1344.6571124363224, 1352.4041630875465, 912.5847739278612, 919.5769945824861, 948.0614191950058, 948.8576713493031, 957.9904200958041, 5889.646621202656, 5953.523718102541, 5988.490978266809, 5998.640081595118, 6191.473724733448, 441.41512495943005, 458.749981260214, 463.45081078921584, 473.290064902646, 997.7854761226371, 3911.413288006822, 3915.0, 3952.9209415812097, 3955.92088158241, 4013.678905687538, 10367.32612380193, 10567.207459440573, 10607.514947907299, 10612.151027917747, 10676.359418434919, 10552.841707374242, 10557.683269501927, 10564.260501764802, 10570.52432640533, 10571.04860562553, 8140.0, 8199.836003280021, 8232.423730338818, 8280.04779450359, 8352.791180220422, 5741.45456181662, 5787.276590426197, 5788.189653448437, 5973.402659733992, 6023.867778498991, 9400.435973841591, 9609.855852162083, 9740.220782337254, 9741.977092405195, 9750.609975600897, 9147.993720690585, 9522.666706665234, 9539.618415263312, 9698.0, 9886.060824221688, 6296.33888441707, 6409.551331406757, 6567.605943643397, 6569.704363303758, 7475.177730449537, 7505.6997720090585, 7550.207228240955, 7609.429292802951, 7957.442978991415, 8147.877781833396, 7230.530015548972, 7340.926590734024, 7411.444141689287, 7509.324160825552, 7527.2849079337375, 7182.138143422822, 7250.528715633467, 7255.0, 7265.273472652692, 7274.563526188445, 6848.041274221513, 6931.341522555349, 6932.202796678446, 6936.2023367311385, 6941.722331106901, 4845.345878306383, 4882.096812089775, 4882.414110306786, 4981.850544483671, 5050.0, 4887.144749668865, 4890.510948905081, 4984.302197692398, 5010.974945125251, 5179.067767801832], "value": 4545.602993685878, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.50465516811016, 321.1601661654773, 328.761647805341, 330.11693719300865, 332.4863086531309, 6301.968490157548, 6306.211723534559, 6353.745850165994, 6373.9362606373925, 6492.05865149553, 6119.694015299239, 6339.587926784768, 6352.491800655948, 6374.585651932633, 6426.068220108094, 4027.65764909983, 4502.639788816895, 4700.1539722849875, 4702.459217190016, 4710.670253082286, 4669.626429885618, 4669.929951050737, 4679.789409476582, 4685.62514998801, 4695.6478264130155, 4646.117237724821, 4691.179043667362, 4691.5073917238715, 4698.929516057245, 4706.976465117669, 3859.1316953685478, 4671.5795578397765, 4695.6478264130155, 4699.013207226487, 4708.788104535304, 3707.535523468215, 3844.711646626486, 3940.271049855786, 3998.680105591575, 4007.2786898358286, 6094.664793436359, 6130.0, 6141.078838174265, 6144.723487443076, 6161.32225455204, 5513.173024046385, 5527.806526771544, 5528.391876893538, 5533.5849811263915, 5561.360443549003, 6808.183018037817, 6834.82180785932, 6843.315668433117, 6844.110265665432, 6847.075644787939, 3750.587435382106, 4493.865184044451, 4512.526184750587, 4517.186906356855, 4521.570450807168, 6241.28225254097, 6724.361185687352, 7093.92906070943, 7115.679794409212, 7215.314545118205, 4478.350639157304, 4511.661625378109, 4526.637868970541, 4527.954720452819, 4560.771961401917, 3898.5126859142606, 4640.605548528413, 4642.814287428532, 4645.883852903637, 4655.138799322136, 5450.400455949843, 5453.945460545423, 5463.399026107125, 5476.342838859362, 5495.0, 6861.245263021064, 6876.209235937831, 6881.174259088842, 6910.2744211858035, 6993.0908981832035, 1566.6631674189875, 1587.8888477806443, 1589.0465720567568, 1592.283472437408, 1593.2352470813842, 989.5794287427958, 1016.7915577306634, 1028.3007554862616, 1030.6701855406197, 1036.3315661398356, 6335.746570137235, 6429.45349645276, 6444.0, 6454.677266136674, 6472.967635161794, 7108.822279443055, 7124.643767811693, 7125.5368401053765, 7138.464615153885, 7153.535020223669, 5336.786528538815, 6423.357664233539, 6424.293327733945, 6435.4208121268375, 6437.903431448532, 3711.0536813112485, 3757.28611563802, 3760.398336266239, 3762.6049264827348, 3764.454154147688, 7105.92894071063, 7374.778756637309, 7403.9629801851725, 7476.738314158978, 7500.549967001997, 7062.717491300393, 7334.3032411920785, 7363.77908662741, 7387.556746595222, 7399.48203625741, 5756.769729210785, 7348.522346047596, 7377.446691498159, 7432.554046757104, 7503.5497870127965, 6830.521863469509, 6951.7914462566205, 7179.928200717926, 7187.101612298463, 7211.1707153678, 6069.484093852072, 7043.36609705129, 7048.471364647775, 7065.399441047363, 7084.2915708428745, 7508.174100848793, 7509.136449308399, 7511.136219334847, 7520.548767073774, 7546.9245307546225, 4519.0284088919725, 4563.2242518772555, 4567.703099298535, 4570.520095389937, 4582.816687332469, 6792.76225332111, 7106.964465177641, 7109.644517773987, 7124.5725256484775, 7165.605891676008, 6844.931550684629, 6912.654367281515, 6938.27148149437, 6943.0, 6985.1967023790885, 6962.0, 6962.582245065312, 6966.79099627012, 6971.0, 7007.299270072952, 6777.423918966943, 6783.152105986752, 6788.558743681842, 6789.864202715819, 6793.72825086991, 6573.868522629617, 6896.827579310558, 6898.034275201376, 6908.620025898624, 6924.272951340036, 5524.0, 5671.744771485375, 6680.632565208765, 6706.396424321834, 6791.117154769849], "value": 4988.651641421539, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.7720075997466, 673.8820706376383, 726.2700985509562, 754.6415452659984, 755.1504557372955, 14703.41186352546, 14886.25568721565, 15065.472708455207, 15072.84927150731, 15104.471343502979, 13880.514182003632, 14114.294285285747, 14135.293235338218, 14136.50522231722, 14388.568342949702, 728.7412968396222, 738.3170567225295, 910.2354022621014, 913.5204017890627, 939.4175611121115, 926.4950601921969, 935.0602644342426, 936.4381371177308, 938.1837801113041, 938.9530523473833, 978.0659470205936, 985.3348989432141, 985.6599473181741, 986.2159583131391, 987.8172538080443, 760.8516339313836, 973.6738192705452, 977.8239916814971, 978.046404755365, 978.6183388478499, 723.9900339027081, 724.3372314332432, 724.7572063358781, 725.329070609684, 725.742361461684, 12863.356832158355, 13009.349532523336, 13037.15258508194, 13093.672658183526, 13125.868741312655, 12406.25562466255, 12440.315782631951, 12662.176958497761, 12706.809397858948, 12748.235105893677, 9836.754081147958, 10063.0, 10096.394216347042, 10205.48972551377, 10715.249932504728, 3464.116650254169, 3466.2201004773974, 3500.3524347995703, 3507.5615548056494, 3619.1314084619767, 77762.61118694427, 78093.60953195255, 78126.6093669528, 83118.0, 83359.0, 9453.243740500744, 9469.384490008128, 9483.857742133874, 9553.90446095544, 9571.28215383849, 18067.638647227246, 21132.47168820792, 21672.783272167075, 21699.457513562287, 21742.565148697253, 7446.888296675555, 7562.243775622504, 7671.194524574951, 7777.494462859896, 7878.960605196938, 4051.39229115635, 4057.655099316532, 4069.918601627951, 4076.1694356953208, 4332.718373305788, 3565.8038807865814, 3614.8192590370377, 3629.4555816627717, 3636.4545318201954, 3638.308721342936, 434.5307068366157, 637.4581605635266, 639.3925770518026, 645.2063961327593, 656.5010591950153, 554.3043480432032, 554.6644280210414, 569.7151424287856, 571.1632458448393, 579.138071101411, 7598.582077985653, 7800.9609951951015, 7897.091834438998, 7899.131095579482, 7928.365730741527, 481.5208867177181, 549.2530158983778, 553.6788662575697, 553.97784088636, 558.974846131925, 3625.927481450409, 3712.201876596545, 3777.7355585109326, 3799.601041890617, 3807.314683357022, 90282.0, 90604.54697726469, 91607.54196228976, 91850.0, 92090.61864072044, 79227.0, 79461.0134746636, 79942.60028699819, 80858.59570702109, 81535.77696334559, 69880.0, 71075.93386099213, 71124.64437677882, 71183.64408177926, 72779.63610181915, 7472.36484898768, 7561.357284630868, 7589.772306830804, 7636.0, 7729.458937874295, 45955.770221150015, 47834.28248576204, 49758.50724478271, 50133.99732005412, 51474.48525514697, 61444.38555614387, 61482.0, 61530.0, 61872.07191892035, 64412.677936610016, 10294.330868493524, 10414.583416663248, 10878.673639790819, 11132.833007505056, 11615.070794336601, 12024.819627705416, 12071.39643017863, 12218.327991960172, 12243.265404075784, 12351.567695130627, 10906.836397454203, 11495.080393568662, 11602.94198529002, 11643.010344120506, 11645.41772911368, 11206.663800086011, 11437.199396042197, 11494.137939654727, 11525.827112593486, 12139.210951288138, 11022.559097636005, 11243.161667609858, 11287.548498059985, 11660.591879284184, 11714.297142171497, 9441.811163776549, 9516.476593787409, 9699.0, 9748.0, 9958.15355694774, 9441.669541566012, 9460.905390946002, 9475.905240947777, 9575.808483830424, 9594.66418675343], "value": 6813.289593271921, "unit": "score"}], "name": "dromaeo_css", "value": 7368.992221072266, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 117.0, 112.0, 114.0, 113.0, 112.0, 113.0, 114.0, 134.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 123.0, 160.0, 157.0, 119.0, 142.0, 137.0, 130.0, 168.0, 138.0], "value": 142.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 186.0, 186.0, 184.0, 185.0, 184.0, 184.0, 186.0, 184.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 78.0, 73.0, 152.0, 56.0, 55.0, 57.0, 67.0, 133.0, 61.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 140.0, 77.0, 105.0, 129.0, 134.0, 80.0, 75.0, 141.0], "value": 106.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 91.0, 95.0, 91.0, 94.0, 92.0, 94.0, 90.0, 94.0, 87.0], "value": 92.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 97.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 86.0, 84.0, 84.0, 84.0, 83.0, 87.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 64.0, 62.0, 63.0, 64.0, 65.0, 79.0, 61.0, 58.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 66.0, 75.0, 72.0, 73.0, 76.0, 69.0, 66.0, 67.0], "value": 69.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 79.0, 96.0, 103.0, 76.0, 80.0, 81.0, 79.0, 85.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 131.0, 130.0, 130.0, 129.0, 128.0, 128.0, 130.0, 181.0, 161.0], "value": 137.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 169.0, 166.0, 166.0, 165.0, 168.0, 171.0, 161.0, 170.0, 162.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 68.0, 67.0, 69.0, 73.0, 94.0, 94.0, 69.0, 62.0], "value": 73.3, "unit": "score"}], "name": "kraken", "value": 1499.3, "alertThreshold": 2.0}]} 02:48:52 INFO - Return code: 0 02:48:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:48:52 ERROR - # TBPL SUCCESS # 02:48:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:48:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:48:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:48:52 INFO - 2016-06-06 02:48:52.627 screenresolution[1679:9653] starting screenresolution argv=screenresolution get 02:48:52 INFO - 2016-06-06 02:48:52.630 screenresolution[1679:9653] Display 0: 1600x1200x32@60 02:48:52 INFO - 2016-06-06 02:48:52.636 screenresolution[1680:9656] starting screenresolution argv=screenresolution list 02:48:52 INFO - Available Modes on Display 0 02:48:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:48:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:48:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:48:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:48:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:48:52 INFO - Intel Iris: 02:48:52 INFO - Chipset Model: Intel Iris 02:48:52 INFO - Type: GPU 02:48:52 INFO - Bus: Built-In 02:48:52 INFO - VRAM (Dynamic, Max): 1536 MB 02:48:52 INFO - Vendor: Intel (0x8086) 02:48:52 INFO - Device ID: 0x0a2e 02:48:52 INFO - Revision ID: 0x0009 02:48:52 INFO - Displays: 02:48:52 INFO - Display: 02:48:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:48:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:48:52 INFO - Display Serial Number: 1600x1200 60 02:48:52 INFO - Main Display: Yes 02:48:52 INFO - Mirror: Off 02:48:52 INFO - Online: Yes 02:48:52 INFO - Rotation: Supported 02:48:52 INFO - Return code: 0 02:48:52 INFO - Running post-action listener: _resource_record_post_action 02:48:52 INFO - Running post-run listener: _resource_record_post_run 02:48:53 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 36873728; Write bytes: 651581440; Read time: 249; Write time: 1814 02:48:53 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 350179840; Write bytes: 338314752; Read time: 27472; Write time: 689 02:48:53 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 30699008; Write bytes: 294973952; Read time: 186; Write time: 1070 02:48:53 INFO - Running post-run listener: _upload_blobber_files 02:48:53 INFO - Blob upload gear active. 02:48:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:48:53 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 02:48:53 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:48:53 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:48:54 INFO - (blobuploader) - INFO - Open directory for files ... 02:48:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:48:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:48:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:55 INFO - (blobuploader) - INFO - Done attempting. 02:48:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:48:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:48:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:56 INFO - (blobuploader) - INFO - Done attempting. 02:48:56 INFO - (blobuploader) - INFO - Iteration through files over. 02:48:56 INFO - Return code: 0 02:48:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:48:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:48:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1dd40de342200948d9160d331ac1dfc03ffdbfca5d23fb97bc7bfdd9a688ab071269fec179e30553ffb470469e659361b616a2eaa47b6f21a182fdf693cf1dce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05fb5e258ba1e310278bd96b19016ebe80d93b38804a32c59e84b05d888c3e6e8125e16575be0e6aced23a3d5e4959652861a6574dceef59d5f49f1a26a1f1c5"} 02:48:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:48:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:48:56 INFO - Contents: 02:48:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1dd40de342200948d9160d331ac1dfc03ffdbfca5d23fb97bc7bfdd9a688ab071269fec179e30553ffb470469e659361b616a2eaa47b6f21a182fdf693cf1dce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05fb5e258ba1e310278bd96b19016ebe80d93b38804a32c59e84b05d888c3e6e8125e16575be0e6aced23a3d5e4959652861a6574dceef59d5f49f1a26a1f1c5"} 02:48:56 INFO - Running post-run listener: copy_logs_to_upload_dir 02:48:56 INFO - Copying logs to upload dir... 02:48:56 INFO - mkdir: /builds/slave/test/build/upload/logs 02:48:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1290.286795 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-06-06 02:48:56.720082) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 02:48:56.724098) ========= 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.5QuZAVLVIz/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.KPHhAWpNQe/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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-Aurora/sha512/1dd40de342200948d9160d331ac1dfc03ffdbfca5d23fb97bc7bfdd9a688ab071269fec179e30553ffb470469e659361b616a2eaa47b6f21a182fdf693cf1dce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05fb5e258ba1e310278bd96b19016ebe80d93b38804a32c59e84b05d888c3e6e8125e16575be0e6aced23a3d5e4959652861a6574dceef59d5f49f1a26a1f1c5"} build_url:https://queue.taskcluster.net/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010738 build_url: 'https://queue.taskcluster.net/v1/task/UijduwHRRKG66bWqIOXvjQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1dd40de342200948d9160d331ac1dfc03ffdbfca5d23fb97bc7bfdd9a688ab071269fec179e30553ffb470469e659361b616a2eaa47b6f21a182fdf693cf1dce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05fb5e258ba1e310278bd96b19016ebe80d93b38804a32c59e84b05d888c3e6e8125e16575be0e6aced23a3d5e4959652861a6574dceef59d5f49f1a26a1f1c5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 02:48:56.755906) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:48:56.756260) ========= 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.5QuZAVLVIz/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.KPHhAWpNQe/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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.005321 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:48:56.805121) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 02:48:56.805497) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 02:48:56.805931) ========= ========= Total master_lag: 0.17 =========