builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0142 starttime: 1461757849.56 results: success (0) buildid: 20160427025603 builduid: 3a5b84d2cfaf4104a9d688815a3e42b2 revision: 488df90abd620538454e58ae0a75f13453f391b1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 04:50:49.555679) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 04:50:49.556082) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 04:50:49.559423) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 04:50:49.577381) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:50:49.577645) ========= 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.HsXtgtBcPJ/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.g3LhpyNZDx/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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-04-27 04:50:49-- 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% 156M=0s 2016-04-27 04:50:49 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125953 ========= master_lag: 0.31 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:50:50.013889) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:50:50.014176) ========= 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.HsXtgtBcPJ/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.g3LhpyNZDx/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.020698 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:50:50.060641) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-27 04:50:50.061024) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 488df90abd620538454e58ae0a75f13453f391b1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 488df90abd620538454e58ae0a75f13453f391b1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HsXtgtBcPJ/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.g3LhpyNZDx/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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-04-27 04:50:50,143 truncating revision to first 12 chars 2016-04-27 04:50:50,143 Setting DEBUG logging. 2016-04-27 04:50:50,143 attempt 1/10 2016-04-27 04:50:50,143 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/488df90abd62?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-27 04:50:52,266 unpacking tar archive at: mozilla-central-488df90abd62/testing/mozharness/ program finished with exit code 0 elapsedTime=2.372788 ========= master_lag: 1.80 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-27 04:50:54.232768) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 04:50:54.233030) ========= script_repo_revision: 488df90abd620538454e58ae0a75f13453f391b1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 04:50:54.236068) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 04:50:54.236372) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 04:50:54.431792) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-27 04:50:54.432046) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HsXtgtBcPJ/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.g3LhpyNZDx/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 04:50:54 INFO - MultiFileLogger online at 20160427 04:50:54 in /builds/slave/test 04:50:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:50:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:50:54 INFO - {'append_to_log': False, 04:50:54 INFO - 'base_work_dir': '/builds/slave/test', 04:50:54 INFO - 'blob_upload_branch': 'Firefox', 04:50:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:50:54 INFO - 'branch': 'Firefox', 04:50:54 INFO - 'buildbot_json_path': 'buildprops.json', 04:50:54 INFO - 'config_files': ('talos/mac_config.py',), 04:50:54 INFO - 'default_actions': ('clobber', 04:50:54 INFO - 'read-buildbot-config', 04:50:54 INFO - 'download-and-extract', 04:50:54 INFO - 'populate-webroot', 04:50:54 INFO - 'create-virtualenv', 04:50:54 INFO - 'install', 04:50:54 INFO - 'run-tests'), 04:50:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:50:54 INFO - 'download_minidump_stackwalk': True, 04:50:54 INFO - 'download_symbols': 'ondemand', 04:50:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:50:54 INFO - 'tooltool.py': '/tools/tooltool.py', 04:50:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:50:54 INFO - '/tools/misc-python/virtualenv.py')}, 04:50:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:50:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:50:54 INFO - 'installer_path': 'installer.exe', 04:50:54 INFO - 'log_level': 'info', 04:50:54 INFO - 'log_name': 'talos', 04:50:54 INFO - 'log_to_console': True, 04:50:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:50:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:50:54 INFO - 'opt_config_files': (), 04:50:54 INFO - 'pip_index': False, 04:50:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:50:54 INFO - 'cmd': ('bash', 04:50:54 INFO - '-c', 04:50:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:50:54 INFO - 'enabled': True, 04:50:54 INFO - 'halt_on_failure': False, 04:50:54 INFO - 'name': 'check_screen_resolution'},), 04:50:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:50:54 INFO - 'cmd': ('bash', 04:50:54 INFO - '-c', 04:50:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:50:54 INFO - 'enabled': True, 04:50:54 INFO - 'halt_on_failure': False, 04:50:54 INFO - 'name': 'check_screen_resolution'},), 04:50:54 INFO - 'run_cmd_checks_enabled': True, 04:50:54 INFO - 'sps_profile': False, 04:50:54 INFO - 'sps_profile_interval': 0, 04:50:54 INFO - 'suite': 'dromaeojs', 04:50:54 INFO - 'system_bits': '32', 04:50:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:50:54 INFO - 'title': 't-yosemite-r7-0142', 04:50:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:50:54 INFO - 'use_talos_json': True, 04:50:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:50:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:50:54 INFO - 'work_dir': 'build'} 04:50:54 INFO - ##### 04:50:54 INFO - ##### Running clobber step. 04:50:54 INFO - ##### 04:50:54 INFO - Running pre-action listener: _resource_record_pre_action 04:50:54 INFO - Running main action method: clobber 04:50:54 INFO - rmtree: /builds/slave/test/build 04:50:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:50:55 INFO - Running post-action listener: _resource_record_post_action 04:50:55 INFO - ##### 04:50:55 INFO - ##### Running read-buildbot-config step. 04:50:55 INFO - ##### 04:50:55 INFO - Running pre-action listener: _resource_record_pre_action 04:50:55 INFO - Running main action method: read_buildbot_config 04:50:55 INFO - Using buildbot properties: 04:50:55 INFO - { 04:50:55 INFO - "project": "", 04:50:55 INFO - "product": "firefox", 04:50:55 INFO - "script_repo_revision": "production", 04:50:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:50:55 INFO - "repository": "", 04:50:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:50:55 INFO - "buildid": "20160427025603", 04:50:55 INFO - "pgo_build": "False", 04:50:55 INFO - "basedir": "/builds/slave/test", 04:50:55 INFO - "buildnumber": 240, 04:50:55 INFO - "slavename": "t-yosemite-r7-0142", 04:50:55 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:50:55 INFO - "platform": "yosemite_r7", 04:50:55 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:50:55 INFO - "branch": "mozilla-central", 04:50:55 INFO - "repo_path": "mozilla-central", 04:50:55 INFO - "revision": "488df90abd620538454e58ae0a75f13453f391b1", 04:50:55 INFO - "stage_platform": "macosx64", 04:50:55 INFO - "builduid": "3a5b84d2cfaf4104a9d688815a3e42b2", 04:50:55 INFO - "slavebuilddir": "test" 04:50:55 INFO - } 04:50:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:50:55 INFO - Running post-action listener: _resource_record_post_action 04:50:55 INFO - ##### 04:50:55 INFO - ##### Running download-and-extract step. 04:50:55 INFO - ##### 04:50:55 INFO - Running pre-action listener: _resource_record_pre_action 04:50:55 INFO - Running main action method: download_and_extract 04:50:55 INFO - mkdir: /builds/slave/test/build/tests 04:50:55 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')]} 04:50:55 INFO - https://queue.taskcluster.net/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:50:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:50:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:50:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:50:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 04:50:56 INFO - Downloaded 1199 bytes. 04:50:56 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:50:56 INFO - Using the following test package requirements: 04:50:56 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:50:56 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:50:56 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:50:56 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:50:56 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:50:56 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:50:56 INFO - u'jsshell-mac.zip'], 04:50:56 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:50:56 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:50:56 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:50:56 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:50:56 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:50:56 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:50:56 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:50:56 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:50:56 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:50:56 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:50:56 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:50:56 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:50:56 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')]} 04:50:56 INFO - https://queue.taskcluster.net/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:50:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:50:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:50:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:50:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:50:58 INFO - Downloaded 18114933 bytes. 04:50:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:50:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:51:01 INFO - Return code: 0 04:51:01 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:51:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:01 INFO - https://queue.taskcluster.net/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:51:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:51:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:51:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:51:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:51:03 INFO - Downloaded 18114933 bytes. 04:51:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:51:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:51:07 INFO - Return code: 0 04:51:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:07 INFO - https://queue.taskcluster.net/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:51:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:51:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:51:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:51:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:51:09 INFO - Downloaded 10840145 bytes. 04:51:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:51:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:51:09 INFO - Return code: 0 04:51:09 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')]} 04:51:09 INFO - https://queue.taskcluster.net/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:51:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:51:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:51:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:51:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 04:51:12 INFO - Downloaded 110224400 bytes. 04:51:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:51:12 INFO - mkdir: /builds/slave/test/properties 04:51:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:51:12 INFO - Writing to file /builds/slave/test/properties/build_url 04:51:12 INFO - Contents: 04:51:12 INFO - build_url:https://queue.taskcluster.net/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:51:13 INFO - Running post-action listener: _resource_record_post_action 04:51:13 INFO - Running post-action listener: set_extra_try_arguments 04:51:13 INFO - ##### 04:51:13 INFO - ##### Running populate-webroot step. 04:51:13 INFO - ##### 04:51:13 INFO - Running pre-action listener: _resource_record_pre_action 04:51:13 INFO - Running main action method: populate_webroot 04:51:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:51:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:51:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:51:13 INFO - '--tppagecycles', 04:51:13 INFO - '7'], 04:51:13 INFO - 'tests': ['tsvgm']}}, 04:51:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:51:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:51:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:51:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:51:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:51:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:51:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:13 INFO - 'talos_options': ['--disable-e10s'], 04:51:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:51:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:51:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:13 INFO - 'talos_options': ['--disable-e10s'], 04:51:13 INFO - 'tests': ['damp', 'tps']}, 04:51:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:13 INFO - 'tests': ['damp', 'tps']}, 04:51:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:51:13 INFO - 'tests': ['dromaeo_dom']}, 04:51:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:51:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:51:13 INFO - 'tests': ['a11yr', 04:51:13 INFO - 'ts_paint', 04:51:13 INFO - 'tpaint', 04:51:13 INFO - 'sessionrestore', 04:51:13 INFO - 'sessionrestore_no_auto_restore', 04:51:13 INFO - 'tabpaint']}, 04:51:13 INFO - 'other-e10s': {'tests': ['a11yr', 04:51:13 INFO - 'ts_paint', 04:51:13 INFO - 'tpaint', 04:51:13 INFO - 'sessionrestore', 04:51:13 INFO - 'sessionrestore_no_auto_restore', 04:51:13 INFO - 'tabpaint']}, 04:51:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:51:13 INFO - 'ts_paint', 04:51:13 INFO - 'tpaint', 04:51:13 INFO - 'sessionrestore', 04:51:13 INFO - 'sessionrestore_no_auto_restore', 04:51:13 INFO - 'tabpaint']}, 04:51:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:51:13 INFO - 'ts_paint', 04:51:13 INFO - 'tpaint', 04:51:13 INFO - 'sessionrestore', 04:51:13 INFO - 'sessionrestore_no_auto_restore', 04:51:13 INFO - 'tabpaint']}, 04:51:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:51:13 INFO - 'tests': ['a11yr', 04:51:13 INFO - 'ts_paint', 04:51:13 INFO - 'tpaint', 04:51:13 INFO - 'sessionrestore', 04:51:13 INFO - 'sessionrestore_no_auto_restore', 04:51:13 INFO - 'tabpaint']}, 04:51:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:51:13 INFO - 'tests': ['a11yr', 04:51:13 INFO - 'ts_paint', 04:51:13 INFO - 'tpaint', 04:51:13 INFO - 'sessionrestore', 04:51:13 INFO - 'sessionrestore_no_auto_restore', 04:51:13 INFO - 'tabpaint']}, 04:51:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:51:13 INFO - 'tests': ['tsvgx', 04:51:13 INFO - 'tsvgr_opacity', 04:51:13 INFO - 'tart', 04:51:13 INFO - 'tscrollx', 04:51:13 INFO - 'cart']}, 04:51:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:51:13 INFO - 'tsvgr_opacity', 04:51:13 INFO - 'tart', 04:51:13 INFO - 'tscrollx', 04:51:13 INFO - 'cart']}, 04:51:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:13 INFO - 'talos_options': ['--disable-e10s'], 04:51:13 INFO - 'tests': ['tp5o']}, 04:51:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:13 INFO - 'tests': ['tp5o']}, 04:51:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:13 INFO - 'talos_options': ['--disable-e10s', 04:51:13 INFO - '--xperf_path', 04:51:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:51:13 INFO - 'tests': ['tp5n']}, 04:51:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:13 INFO - 'talos_options': ['--xperf_path', 04:51:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:51:13 INFO - 'tests': ['tp5n']}}, 04:51:13 INFO - 'talos.zip': {'path': '', 04:51:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:51:13 INFO - Running post-action listener: _resource_record_post_action 04:51:13 INFO - ##### 04:51:13 INFO - ##### Running create-virtualenv step. 04:51:13 INFO - ##### 04:51:13 INFO - Running pre-action listener: _resource_record_pre_action 04:51:13 INFO - Running main action method: create_virtualenv 04:51:13 INFO - Creating virtualenv /builds/slave/test/build/venv 04:51:13 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 04:51:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:51:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:51:13 INFO - Using real prefix '/tools/python27' 04:51:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:51:14 INFO - Installing distribute.............................................................................................................................................................................................done. 04:51:17 INFO - Installing pip.................done. 04:51:17 INFO - Return code: 0 04:51:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:51: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')]} 04:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51: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')]} 04:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c510a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c520420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa98551ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3afe00>, '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 0x10c1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3aab20>, '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.HsXtgtBcPJ/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.g3LhpyNZDx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 04:51: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', 'pip>=1.5'] in /builds/slave/test/build 04:51: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 pip>=1.5 04:51:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HsXtgtBcPJ/Render', 04:51:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:17 INFO - 'HOME': '/Users/cltbld', 04:51:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:17 INFO - 'LOGNAME': 'cltbld', 04:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:17 INFO - 'MOZ_NO_REMOTE': '1', 04:51:17 INFO - 'NO_EM_RESTART': '1', 04:51:17 INFO - 'PAGER': '/bin/cat', 04:51:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:17 INFO - 'PWD': '/builds/slave/test', 04:51:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:17 INFO - 'SHELL': '/bin/bash', 04:51:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g3LhpyNZDx/Listeners', 04:51:17 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 04:51:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:17 INFO - 'USER': 'cltbld', 04:51:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:17 INFO - 'XPC_FLAGS': '0x0', 04:51:17 INFO - 'XPC_SERVICE_NAME': '0', 04:51:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:17 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 04:51:17 INFO - Cleaning up... 04:51:17 INFO - Return code: 0 04:51:17 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:51: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')]} 04:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51: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')]} 04:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51: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', '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 0x10c510a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c520420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa98551ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3afe00>, '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 0x10c1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3aab20>, '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.HsXtgtBcPJ/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.g3LhpyNZDx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 04:51: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', 'psutil>=0.7.1'] in /builds/slave/test/build 04:51: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 psutil>=0.7.1 04:51:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HsXtgtBcPJ/Render', 04:51:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:17 INFO - 'HOME': '/Users/cltbld', 04:51:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:17 INFO - 'LOGNAME': 'cltbld', 04:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:17 INFO - 'MOZ_NO_REMOTE': '1', 04:51:17 INFO - 'NO_EM_RESTART': '1', 04:51:17 INFO - 'PAGER': '/bin/cat', 04:51:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:17 INFO - 'PWD': '/builds/slave/test', 04:51:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:17 INFO - 'SHELL': '/bin/bash', 04:51:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g3LhpyNZDx/Listeners', 04:51:17 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 04:51:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:17 INFO - 'USER': 'cltbld', 04:51:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:17 INFO - 'XPC_FLAGS': '0x0', 04:51:17 INFO - 'XPC_SERVICE_NAME': '0', 04:51:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:17 INFO - Downloading/unpacking psutil>=0.7.1 04:51: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 04:51: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 04:51: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 04:51: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 04:51: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 04:51: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 04:51:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:51:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:51:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:51:20 INFO - Installing collected packages: psutil 04:51:20 INFO - Running setup.py install for psutil 04:51:20 INFO - building 'psutil._psutil_osx' extension 04:51:20 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 04:51:21 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 04:51:21 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 04:51:21 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 04:51:21 INFO - building 'psutil._psutil_posix' extension 04:51:21 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 04:51:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:51:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:51:21 INFO - ^ 04:51:21 INFO - 1 warning generated. 04:51:21 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 04:51:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:51:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:51:21 INFO - Successfully installed psutil 04:51:21 INFO - Cleaning up... 04:51:21 INFO - Return code: 0 04:51:21 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:51:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c510a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c520420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa98551ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3afe00>, '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 0x10c1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3aab20>, '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.HsXtgtBcPJ/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.g3LhpyNZDx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 04:51:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:51:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:51:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HsXtgtBcPJ/Render', 04:51:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:21 INFO - 'HOME': '/Users/cltbld', 04:51:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:21 INFO - 'LOGNAME': 'cltbld', 04:51:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:21 INFO - 'MOZ_NO_REMOTE': '1', 04:51:21 INFO - 'NO_EM_RESTART': '1', 04:51:21 INFO - 'PAGER': '/bin/cat', 04:51:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:21 INFO - 'PWD': '/builds/slave/test', 04:51:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:21 INFO - 'SHELL': '/bin/bash', 04:51:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g3LhpyNZDx/Listeners', 04:51:21 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 04:51:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:21 INFO - 'USER': 'cltbld', 04:51:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:21 INFO - 'XPC_FLAGS': '0x0', 04:51:21 INFO - 'XPC_SERVICE_NAME': '0', 04:51:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:22 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:24 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:51:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:51:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:51:24 INFO - Installing collected packages: mozsystemmonitor 04:51:24 INFO - Running setup.py install for mozsystemmonitor 04:51:24 INFO - Successfully installed mozsystemmonitor 04:51:24 INFO - Cleaning up... 04:51:24 INFO - Return code: 0 04:51:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:51:24 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')]} 04:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:24 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')]} 04:51:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:24 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 0x10c510a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c520420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa98551ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3afe00>, '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 0x10c1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3aab20>, '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.HsXtgtBcPJ/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.g3LhpyNZDx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 04:51:24 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 04:51:24 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 04:51:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HsXtgtBcPJ/Render', 04:51:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:24 INFO - 'HOME': '/Users/cltbld', 04:51:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:24 INFO - 'LOGNAME': 'cltbld', 04:51:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:24 INFO - 'MOZ_NO_REMOTE': '1', 04:51:24 INFO - 'NO_EM_RESTART': '1', 04:51:24 INFO - 'PAGER': '/bin/cat', 04:51:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:24 INFO - 'PWD': '/builds/slave/test', 04:51:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:24 INFO - 'SHELL': '/bin/bash', 04:51:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g3LhpyNZDx/Listeners', 04:51:24 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 04:51:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:24 INFO - 'USER': 'cltbld', 04:51:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:24 INFO - 'XPC_FLAGS': '0x0', 04:51:24 INFO - 'XPC_SERVICE_NAME': '0', 04:51:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:25 INFO - Downloading/unpacking blobuploader==1.2.4 04:51:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:27 INFO - Downloading blobuploader-1.2.4.tar.gz 04:51:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:51:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:51:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:28 INFO - Downloading docopt-0.6.1.tar.gz 04:51:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:51:28 INFO - Installing collected packages: blobuploader, requests, docopt 04:51:28 INFO - Running setup.py install for blobuploader 04:51:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:51:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:51:28 INFO - Running setup.py install for requests 04:51:29 INFO - Running setup.py install for docopt 04:51:29 INFO - Successfully installed blobuploader requests docopt 04:51:29 INFO - Cleaning up... 04:51:29 INFO - Return code: 0 04:51:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:51: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')]} 04:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51: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')]} 04:51:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c510a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c520420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa98551ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3afe00>, '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 0x10c1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3aab20>, '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.HsXtgtBcPJ/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.g3LhpyNZDx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 04:51:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:51:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:51:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HsXtgtBcPJ/Render', 04:51:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:29 INFO - 'HOME': '/Users/cltbld', 04:51:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:29 INFO - 'LOGNAME': 'cltbld', 04:51:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:29 INFO - 'MOZ_NO_REMOTE': '1', 04:51:29 INFO - 'NO_EM_RESTART': '1', 04:51:29 INFO - 'PAGER': '/bin/cat', 04:51:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:29 INFO - 'PWD': '/builds/slave/test', 04:51:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:29 INFO - 'SHELL': '/bin/bash', 04:51:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g3LhpyNZDx/Listeners', 04:51:29 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 04:51:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:29 INFO - 'USER': 'cltbld', 04:51:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:29 INFO - 'XPC_FLAGS': '0x0', 04:51:29 INFO - 'XPC_SERVICE_NAME': '0', 04:51:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:51:29 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-nU8VG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:51:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-A_aSsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:51:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Uoeqga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:51:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-gPNfPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:51:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-PayvZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:51:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-OZwoEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:51:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-3kRxYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:51:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-whakv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:51:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-I6Xmj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:51:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-BoJdY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:51:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-RnhbbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:51:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-TDWCod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:51:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-fNcsDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:51:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-g6dU7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-jzQ6SB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:51:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-HIoUvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:51:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-8C9ysv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:51:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:51:31 INFO - Running setup.py install for manifestparser 04:51:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:51:31 INFO - Running setup.py install for mozcrash 04:51:31 INFO - Running setup.py install for mozdebug 04:51:32 INFO - Running setup.py install for mozdevice 04:51:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:51:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:51:32 INFO - Running setup.py install for mozfile 04:51:32 INFO - Running setup.py install for mozhttpd 04:51:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:51:32 INFO - Running setup.py install for mozinfo 04:51:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:51:32 INFO - Running setup.py install for mozInstall 04:51:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:51:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:51:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:51:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:51:32 INFO - Running setup.py install for mozleak 04:51:32 INFO - Running setup.py install for mozlog 04:51:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:51:33 INFO - Running setup.py install for moznetwork 04:51:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:51:33 INFO - Running setup.py install for mozprocess 04:51:33 INFO - Running setup.py install for mozprofile 04:51:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:51:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:51:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:51:33 INFO - Running setup.py install for mozrunner 04:51:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:51:33 INFO - Running setup.py install for mozscreenshot 04:51:33 INFO - Running setup.py install for moztest 04:51:34 INFO - Running setup.py install for mozversion 04:51:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:51:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:51:34 INFO - Cleaning up... 04:51:34 INFO - Return code: 0 04:51:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c510a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c520420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa98551ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3afe00>, '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 0x10c1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3aab20>, '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.HsXtgtBcPJ/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.g3LhpyNZDx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 04:51:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:51:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:51:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HsXtgtBcPJ/Render', 04:51:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:34 INFO - 'HOME': '/Users/cltbld', 04:51:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:34 INFO - 'LOGNAME': 'cltbld', 04:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:34 INFO - 'MOZ_NO_REMOTE': '1', 04:51:34 INFO - 'NO_EM_RESTART': '1', 04:51:34 INFO - 'PAGER': '/bin/cat', 04:51:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:34 INFO - 'PWD': '/builds/slave/test', 04:51:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:34 INFO - 'SHELL': '/bin/bash', 04:51:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g3LhpyNZDx/Listeners', 04:51:34 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 04:51:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:34 INFO - 'USER': 'cltbld', 04:51:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:34 INFO - 'XPC_FLAGS': '0x0', 04:51:34 INFO - 'XPC_SERVICE_NAME': '0', 04:51:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:51:34 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-T4psei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:51:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:51:34 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-YdlPXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:51:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:51:34 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-yfWUGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:51:34 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)) 04:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:51:34 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-sj_43c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:51:34 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)) 04:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:51:34 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-8XlOhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:51:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:51:35 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-2CYTuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:51:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:51:35 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-7nwrzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:51:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:51:35 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-6Uz_EQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:51:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:51:35 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-15OoP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:51:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:51:35 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-u3GIG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:51:35 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)) 04:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:51:35 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-E5fzlz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:51:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:51:35 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-BmfhaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:51:35 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)) 04:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:51:35 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-HqhBS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:51:35 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)) 04:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:51:35 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-2pylf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:51:36 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)) 04:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:36 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-oW4nYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:51:36 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-CHH6UI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:51:36 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-C_Uliq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:51:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:51: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 04:51: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 04:51: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 04:51: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 04:51: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 04:51: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 04:51:38 INFO - Downloading blessings-1.6.tar.gz 04:51:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:51:38 INFO - Installing collected packages: blessings 04:51:38 INFO - Running setup.py install for blessings 04:51:38 INFO - Successfully installed blessings 04:51:38 INFO - Cleaning up... 04:51:39 INFO - Return code: 0 04:51:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:51:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:51:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:51:39 INFO - Reading from file tmpfile_stdout 04:51:39 INFO - Current package versions: 04:51:39 INFO - blessings == 1.6 04:51:39 INFO - blobuploader == 1.2.4 04:51:39 INFO - docopt == 0.6.1 04:51:39 INFO - manifestparser == 1.1 04:51:39 INFO - mozInstall == 1.12 04:51:39 INFO - mozcrash == 0.17 04:51:39 INFO - mozdebug == 0.1 04:51:39 INFO - mozdevice == 0.48 04:51:39 INFO - mozfile == 1.2 04:51:39 INFO - mozhttpd == 0.7 04:51:39 INFO - mozinfo == 0.9 04:51:39 INFO - mozleak == 0.1 04:51:39 INFO - mozlog == 3.1 04:51:39 INFO - moznetwork == 0.27 04:51:39 INFO - mozprocess == 0.22 04:51:39 INFO - mozprofile == 0.28 04:51:39 INFO - mozrunner == 6.11 04:51:39 INFO - mozscreenshot == 0.1 04:51:39 INFO - mozsystemmonitor == 0.0 04:51:39 INFO - moztest == 0.7 04:51:39 INFO - mozversion == 1.4 04:51:39 INFO - psutil == 3.1.1 04:51:39 INFO - requests == 1.2.3 04:51:39 INFO - wsgiref == 0.1.2 04:51:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:51: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')]} 04:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51: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')]} 04:51:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:39 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 0x10c510a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c520420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa98551ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3afe00>, '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 0x10c1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3aab20>, '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.HsXtgtBcPJ/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.g3LhpyNZDx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 04:51:39 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 04:51:39 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 04:51:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HsXtgtBcPJ/Render', 04:51:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:39 INFO - 'HOME': '/Users/cltbld', 04:51:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:39 INFO - 'LOGNAME': 'cltbld', 04:51:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:39 INFO - 'MOZ_NO_REMOTE': '1', 04:51:39 INFO - 'NO_EM_RESTART': '1', 04:51:39 INFO - 'PAGER': '/bin/cat', 04:51:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:39 INFO - 'PWD': '/builds/slave/test', 04:51:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:39 INFO - 'SHELL': '/bin/bash', 04:51:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g3LhpyNZDx/Listeners', 04:51:39 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 04:51:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:39 INFO - 'USER': 'cltbld', 04:51:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:39 INFO - 'XPC_FLAGS': '0x0', 04:51:39 INFO - 'XPC_SERVICE_NAME': '0', 04:51:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:39 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)) 04:51:39 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)) 04:51:39 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)) 04:51:39 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)) 04:51:39 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)) 04:51:39 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)) 04:51:39 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)) 04:51:39 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)) 04:51:39 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)) 04:51:39 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)) 04:51:39 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)) 04:51:39 INFO - Cleaning up... 04:51:39 INFO - Return code: 0 04:51:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:51: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')]} 04:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51: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')]} 04:51:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c510a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c520420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa98551ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3afe00>, '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 0x10c1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3aab20>, '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.HsXtgtBcPJ/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.g3LhpyNZDx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 04:51: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', 'jsonschema'] in /builds/slave/test/build 04:51: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 jsonschema 04:51:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HsXtgtBcPJ/Render', 04:51:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:39 INFO - 'HOME': '/Users/cltbld', 04:51:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:39 INFO - 'LOGNAME': 'cltbld', 04:51:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:39 INFO - 'MOZ_NO_REMOTE': '1', 04:51:39 INFO - 'NO_EM_RESTART': '1', 04:51:39 INFO - 'PAGER': '/bin/cat', 04:51:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:39 INFO - 'PWD': '/builds/slave/test', 04:51:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:39 INFO - 'SHELL': '/bin/bash', 04:51:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g3LhpyNZDx/Listeners', 04:51:39 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 04:51:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:39 INFO - 'USER': 'cltbld', 04:51:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:39 INFO - 'XPC_FLAGS': '0x0', 04:51:39 INFO - 'XPC_SERVICE_NAME': '0', 04:51:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:39 INFO - Downloading/unpacking jsonschema 04:51: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 04:51: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 04:51: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 04:51: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 04:51: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 04:51: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 04:51:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:51:42 INFO - Downloading/unpacking functools32 (from jsonschema) 04:51: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 04:51: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 04:51: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 04:51: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 04:51: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 04:51: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 04:51:42 INFO - Downloading functools32-3.2.3-2.tar.gz 04:51:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:51:42 INFO - warning: no files found matching '*.txt' 04:51:42 INFO - no previously-included directories found matching 'build' 04:51:42 INFO - no previously-included directories found matching 'dist' 04:51:42 INFO - no previously-included directories found matching '.git*' 04:51:42 INFO - Installing collected packages: jsonschema, functools32 04:51:42 INFO - Running setup.py install for functools32 04:51:42 INFO - warning: no files found matching '*.txt' 04:51:42 INFO - no previously-included directories found matching 'build' 04:51:42 INFO - no previously-included directories found matching 'dist' 04:51:42 INFO - no previously-included directories found matching '.git*' 04:51:42 INFO - Successfully installed jsonschema functools32 04:51:42 INFO - Cleaning up... 04:51:43 INFO - Return code: 0 04:51:43 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:51:43 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')]} 04:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:43 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')]} 04:51:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:43 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 0x10c510a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c520420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa98551ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3afe00>, '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 0x10c1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3aab20>, '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.HsXtgtBcPJ/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.g3LhpyNZDx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 04:51:43 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 04:51:43 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 04:51:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HsXtgtBcPJ/Render', 04:51:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:43 INFO - 'HOME': '/Users/cltbld', 04:51:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:43 INFO - 'LOGNAME': 'cltbld', 04:51:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:43 INFO - 'MOZ_NO_REMOTE': '1', 04:51:43 INFO - 'NO_EM_RESTART': '1', 04:51:43 INFO - 'PAGER': '/bin/cat', 04:51:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:43 INFO - 'PWD': '/builds/slave/test', 04:51:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:43 INFO - 'SHELL': '/bin/bash', 04:51:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g3LhpyNZDx/Listeners', 04:51:43 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 04:51:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:43 INFO - 'USER': 'cltbld', 04:51:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:43 INFO - 'XPC_FLAGS': '0x0', 04:51:43 INFO - 'XPC_SERVICE_NAME': '0', 04:51:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:43 INFO - Downloading/unpacking flake8 04:51: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 04:51: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 04:51: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 04:51: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 04:51: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 04:51: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 04:51:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:51:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:51:45 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 04:51:45 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 04:51:45 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 04:51:45 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 04:51:45 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 04:51:45 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 04:51:46 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:51:46 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:46 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:51:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:51:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:51:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:51:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:51:46 INFO - no previously-included directories found matching 'docs/_build' 04:51:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:51:47 INFO - Running setup.py install for pep8 04:51:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:51:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:51:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:51:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:51:47 INFO - no previously-included directories found matching 'docs/_build' 04:51:47 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:51:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:51:47 INFO - Cleaning up... 04:51:47 INFO - Return code: 0 04:51:47 INFO - Running post-action listener: _resource_record_post_action 04:51:47 INFO - Running post-action listener: _start_resource_monitoring 04:51:47 INFO - Starting resource monitoring. 04:51:47 INFO - ##### 04:51:47 INFO - ##### Running install step. 04:51:47 INFO - ##### 04:51:47 INFO - Running pre-action listener: _resource_record_pre_action 04:51:47 INFO - Running main action method: install 04:51:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:51:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:51:47 INFO - Reading from file tmpfile_stdout 04:51:47 INFO - Detecting whether we're running mozinstall >=1.0... 04:51:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:51:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:51:47 INFO - Reading from file tmpfile_stdout 04:51:47 INFO - Output received: 04:51:47 INFO - Usage: mozinstall [options] installer 04:51:47 INFO - Options: 04:51:47 INFO - -h, --help show this help message and exit 04:51:47 INFO - -d DEST, --destination=DEST 04:51:47 INFO - Directory to install application into. [default: 04:51:47 INFO - "/builds/slave/test"] 04:51:47 INFO - --app=APP Application being installed. [default: firefox] 04:51:47 INFO - mkdir: /builds/slave/test/build/application 04:51:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:51:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:52:21 INFO - Reading from file tmpfile_stdout 04:52:21 INFO - Output received: 04:52:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:52:21 INFO - Running post-action listener: _resource_record_post_action 04:52:21 INFO - ##### 04:52:21 INFO - ##### Running run-tests step. 04:52:21 INFO - ##### 04:52:21 INFO - Running pre-action listener: _resource_record_pre_action 04:52:21 INFO - Running main action method: run_tests 04:52:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:52:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:52:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:52:21 INFO - 2016-04-27 04:52:21.202 screenresolution[1924:11465] starting screenresolution argv=screenresolution get 04:52:21 INFO - 2016-04-27 04:52:21.205 screenresolution[1924:11465] Display 0: 1600x1200x32@60 04:52:21 INFO - 2016-04-27 04:52:21.211 screenresolution[1925:11467] starting screenresolution argv=screenresolution list 04:52:21 INFO - Available Modes on Display 0 04:52:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:52:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:52:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:52:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:52:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:52:21 INFO - Intel Iris: 04:52:21 INFO - Chipset Model: Intel Iris 04:52:21 INFO - Type: GPU 04:52:21 INFO - Bus: Built-In 04:52:21 INFO - VRAM (Dynamic, Max): 1536 MB 04:52:21 INFO - Vendor: Intel (0x8086) 04:52:21 INFO - Device ID: 0x0a2e 04:52:21 INFO - Revision ID: 0x0009 04:52:21 INFO - Displays: 04:52:21 INFO - Display: 04:52:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:52:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:52:21 INFO - Display Serial Number: 1600x1200 60 04:52:21 INFO - Main Display: Yes 04:52:21 INFO - Mirror: Off 04:52:21 INFO - Online: Yes 04:52:21 INFO - Rotation: Supported 04:52:21 INFO - Return code: 0 04:52:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:52:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:52:21 INFO - Python 2.7.3 04:52:21 INFO - Return code: 0 04:52:21 INFO - grabbing minidump binary from tooltool 04:52:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:21 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 0x10c3afe00>, '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 0x10c1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3aab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:52:21 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 04:52:21 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 04:52:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:52:21 INFO - Return code: 0 04:52:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:52:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:52:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:52:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:52:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:52:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:52:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:52:22 INFO - Return code: 0 04:52:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--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 04:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0142 --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 04:52:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HsXtgtBcPJ/Render', 04:52:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:22 INFO - 'HOME': '/Users/cltbld', 04:52:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:22 INFO - 'LOGNAME': 'cltbld', 04:52:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:52:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:22 INFO - 'MOZ_NO_REMOTE': '1', 04:52:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:52:22 INFO - 'NO_EM_RESTART': '1', 04:52:22 INFO - 'PAGER': '/bin/cat', 04:52:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:22 INFO - 'PWD': '/builds/slave/test', 04:52:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:52:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:22 INFO - 'SHELL': '/bin/bash', 04:52:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g3LhpyNZDx/Listeners', 04:52:22 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 04:52:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:22 INFO - 'USER': 'cltbld', 04:52:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:22 INFO - 'XPC_FLAGS': '0x0', 04:52:22 INFO - 'XPC_SERVICE_NAME': '0', 04:52:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--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 04:52:22 INFO - mozversion application_buildid: 20160427025603 04:52:22 INFO - mozversion application_changeset: 488df90abd620538454e58ae0a75f13453f391b1 04:52:22 INFO - mozversion application_display_name: Nightly 04:52:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:52:22 INFO - mozversion application_name: Firefox 04:52:22 INFO - mozversion application_remotingname: firefox 04:52:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:52:22 INFO - mozversion application_vendor: Mozilla 04:52:22 INFO - mozversion application_version: 49.0a1 04:52:22 INFO - mozversion platform_buildid: 20160427025603 04:52:22 INFO - mozversion platform_changeset: 488df90abd620538454e58ae0a75f13453f391b1 04:52:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:52:22 INFO - mozversion platform_version: 49.0a1 04:52:22 INFO - using testdate: 1461757942 04:52:22 INFO - actual date: 1461757942 04:52:22 INFO - starting webserver on 'localhost:51732' 04:52:22 INFO - SUITE-START | Running 2 tests 04:52:22 INFO - TEST-START | dromaeo_css 04:52:22 INFO - operating with platform_type : mac_ 04:52:22 INFO - Initialising browser for dromaeo_css test... 04:52:22 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpTB50bV/profile http://localhost:51732/getInfo.html) 04:52:24 INFO - PROCESS | 1943 | __metrics Screen width/height:1600/1200 04:52:24 INFO - PROCESS | 1943 | colorDepth:24 04:52:24 INFO - PROCESS | 1943 | Browser inner width/height: 1024/689 04:52:24 INFO - PROCESS | 1943 | __metrics 04:52:25 INFO - PROCESS | 1943 | 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" 04:52:25 ERROR - PROCESS | 1943 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:52:25 INFO - TEST-INFO | 1943: exit 0 04:52:25 INFO - Browser initialized. 04:52:25 INFO - Running cycle 1/1 for dromaeo_css test... 04:52:25 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpTB50bV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:52:37 INFO - PROCESS | 1945 | RSS: Main: 239943680 04:52:37 INFO - PROCESS | 1945 | 04:53:29 INFO - PROCESS | 1945 | 1461758009050 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] 04:55:38 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51732/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51732/tests/dromaeo/cssquery-ext.html) 04:55:40 INFO - PROCESS | 1945 | RSS: Main: 422432768 04:55:40 INFO - PROCESS | 1945 | 04:58:43 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51732/tests/dromaeo/cssquery-ext.html (next: http://localhost:51732/tests/dromaeo/cssquery-jquery.html) 04:58:44 INFO - PROCESS | 1945 | RSS: Main: 373678080 04:58:44 INFO - PROCESS | 1945 | 05:01:46 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51732/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51732/tests/dromaeo/cssquery-mootools.html) 05:01:47 INFO - PROCESS | 1945 | RSS: Main: 379789312 05:01:47 INFO - PROCESS | 1945 | 05:04:49 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51732/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51732/tests/dromaeo/cssquery-prototype.html) 05:04:50 INFO - PROCESS | 1945 | RSS: Main: 377950208 05:04:50 INFO - PROCESS | 1945 | 05:07:53 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51732/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51732/tests/dromaeo/cssquery-yui.html) 05:07:54 INFO - PROCESS | 1945 | RSS: Main: 344641536 05:07:54 INFO - PROCESS | 1945 | 05:10:56 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51732/tests/dromaeo/cssquery-yui.html (next: http://localhost:51732/tests/dromaeo/cssquery-dojo.html) 05:10:57 INFO - PROCESS | 1945 | RSS: Main: 386531328 05:10:57 INFO - PROCESS | 1945 | 05:10:57 INFO - PROCESS | 1945 | __start_tp_report 05:10:57 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 05:10:57 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 05:10:57 INFO - PROCESS | 1945 | |i|pagename|runs| 05:10:57 INFO - PROCESS | 1945 | |0;dojo.html;3365.932681346373;3404.2170300830826;3423.1271025888386;3435.1068722132236;3483.738719596031;5643.915341269874;5710.600257981938;5711;5716.1425786131995;5718.22803921471;4781.306710526971;5679.914801277984;5709.400512946143;5724.34170070442;5825.563082768788;5053.469385714502;5076.593872490201;5118.0019896120275;5131.717755523445;5218.1650935850275;5188.533032027117;5225.242339860718;5249.238860365244;5275.551578115865;5309.150535914255;5063.18988961768;5153.742312884341;5161.638685292031;5171.405288391846;5181.455947125554;5107.693538387709;5164.328637277167;5175.922361164586;5222.895542089175;5259.474052594748;5081.974590127063;5135.8202462914;5136.948630513722;5140.563052140573;5209.6874187548865;5615.634983726086;5663.660180389149;5671.347795003586;5680.11958146488;5753.683547404891;6064.241969753781;6108.725107370179;6113.358097399753;6115.541334399938;6122.611623597858;6228.128062071313;6276.309605943343;6313.684315784192;6322.083297921821;6329.905051424233;5638.915416268759;5782.884342313214;5800.8839823203725;5803.071508558653;5814.796482123148;6199.380061993765;6250.49996000328;6283.5287353448675;6292.339304373067;6297.307296197415;6093.268807743099;6094.11635312886;6101.84745381369;6132.294786099566;6146.477549408351;6130.478909292671;6174.197354343996;6259.874802503924;6292.716827742763;6303.149074874925;6108.190302030417;6109.890546868077;6189.690515474208;6233.719482623293;6247.094171345129;6176.505879529627;6209.9068513972325;6216.595921265194;6240.594361366497;6258.43674069336;4825.855224343275;4888.120138375127;4890.364252647133;4893.779779909913;4931.9753401232765;4387.583179597933;4449.065696203768;4481.731096134177;4496.662750293741;4504.121696269196;5677.630953987978;5699.316082070095;5701.173329867146;5701.4013528579735;5709.0865461525955;6229.314775374705;6264.1856558648;6286.4342209201395;6302.716377763011;6304.306526282106;5074;5622.353429355595;5647.548196144298;5698.886022279614;5720.170575266563;5400.135978243462;5402.216678581663;5405.621606487587;5408.242846001563;5409.540189083972;6305;6309.432151106423;6319.778807741707;6328.46208072319;6332.430081292706;6136.7948077883175;6215.223097112861;6314.810555683337;6323.936760632519;6328.145700330397;6232.407921247474;6289.40250676185;6289.49684025286;6298.464630506275;6304.621722696653;5949;6123.326434092157;6141.938580614136;6145.4161854623735;6157.230346206724;4898.608111351012;6144.617461071223;6197.25632924052;6295.1186833842385;6297.779577714758;5839.5036421904615;5995.700214989321;5996;5999.790007349897;6003.159557661844;5912.645241285537;6006.129111278753;6006.249218847644;6016.308124565556;6018.458338749533;5842.620229685057;5869.207656966255;5890.175375447355;5890.734916928663;5897.321807992136;5933.821985340447;5998.850028749316;6035.39646035393;6046;6052.03167493199;5896.823095307148;5911.615744976562;5914.319853216935;5915.733791979285;5920.37836027211 05:10:57 INFO - PROCESS | 1945 | |1;ext.html;498.31730529175036;502.90444815485057;509.8037255656572;511.7338983728461;588.4821357205658;29072;37174.62825371749;38306.99999999996;38339.46642134311;38456.615433845654;41788.58211417885;42308.57691423085;42571.787141064255;42571.78714106433;42576.57423425765;8395.748127556182;9333;9540;9557.665481708142;9578.233741300679;8412.284955778767;11070.169737269736;11327.98048175664;11335.37655428951;11345.716357091098;9506.952465237664;11395.54417823286;11409.600663976762;11410.657680269605;11418.771624567546;8292.212239837207;8514.489130652119;11274.605388811393;11410.885891141148;11433.256838305526;8393.706220282322;8496.405251632388;11064.225504214708;11291.983721465025;11307.038901693366;36893.63106368929;37447;37462.8126859364;37587.43618845719;37718.43422348667;3088.4440800655875;3252.447083995726;3390.16940849491;3441.4321636929876;3503.1067077895227;26942.730572694414;27018.324541886413;27130.5930411044;27141.86429067862;27279.863600682067;2711.877965491538;2736.315921019745;2756.7656749176344;2786.9442611147865;2825.3784167483122;374013;375055;375256;376129;376374.99999999726;60973;61073.38926610677;61157.38842611606;61159.694201528706;61325.38674613286;64234.67882660557;64526.35473645297;64581.3854653628;64585.70828583402;64652.35347646463;15710.68578628421;16164.464375884272;16181.352745890268;16235.675286494205;16261.349546018027;11304.039156671775;11595.014423774073;12360.75278494425;12732.23606583608;12820.423080961378;16631;16863.91568042152;16875.57811054709;16911.32354705798;16912.661746764996;5201.349831271091;5394.9460505394445;6310.021946598285;6326.652034138075;6356.760431715771;1002.2433063037506;1014.832552628808;1022.3865680591734;1078.1374900079904;1101.4712937789907;25457.490850182894;28065.71934280631;28318.71681283202;28564.28589285243;29285;1083.7724077943562;1101.509828126492;1106.3804269608884;1119.462657924201;1175.8471398718284;14881.925590371979;14952.138255120264;14980.40078396852;15011.474598389223;15127.01674391161;446168;455134;456610;456661;456856.87287198857;49967.75016124896;55250;56262.718686406304;57589.71205143948;57619.42380576224;1333.4399552188104;1350.1966330033615;1461.9634509137356;1479.5487376350102;1542.0593438003034;24119.67341796218;27960;28209.332343252318;28451.573226401208;29295;48620.513794863014;49025.01949961052;49396.03623782584;49433.76415589639;49603.25595116004;352912;355399;356267;356723.21638391644;356804;30607;30783.846080769454;36527;37893.431598525494;38071.85784426472;43839.78080109579;44624.33063503985;44785.55214447814;44901.77549112234;45755.77122114368;33177.83411082929;33555;35409.64590354064;36155.81922090373;36328;33983.15042123967;34389.828050859585;34433.655663443045;35027.649723502436;35280;33459.99620011404;33922.66077339195;34276.828615856764;34922.650773492955;35016;29556.704432955394;30591.694083058883;30652.386952260385;30673.07980760581;30818.84590577033;22105.778942211015;23614.645780312967;23713;23746.88126559356;24236.030558777453 05:10:57 INFO - PROCESS | 1945 | |2;jquery.html;371.21488052768404;393.7342293951582;398.16584255983724;400.5193767479026;403.34859202388134;14567.635809104751;16224.594385140377;16450.50648480546;19549.804501954975;20320.796792032073;15608.609784755386;17533.824661753377;19192.94438805865;19415.90292048538;19759;5464.23500709901;5731.914021289663;5733.426657334254;5769.423057694218;5775.364709881909;5456.345238571357;5726.570507211955;5740.225069615611;5785.739641716133;5795.623284486516;5645.266115405012;5664.376918538978;5688.374278829326;5688.800891968782;5692.402297758737;5663.1505274208785;5730.3123625165035;5731.770729170828;5732.054211055212;5739.62692424991;4858.271259311096;5028.245763135523;5055.823046193365;5059.16523773577;5063.468335824759;23847.523049539086;24999;25408;25422.87288563564;25441.491170176494;1932.2464238946823;2077.885716285604;2162.610730068587;2465.3097132802664;2780.402213524102;9199.67801126964;9249.306302027375;9515.667806507094;9634.614615415376;9803.411795292233;1632.6897889401096;1759.13802236903;1802.2430579156785;1804;1823.717323814811;90001.09998900058;96315.84999425043;115548.42225788817;124543;125262;16534.015918089906;19614.607707846048;20683.4829129273;21031.68452473214;21054.157833686786;33955;34720.652793471745;34746.826265868505;34840.30319393598;34913.1271718204;9545.331826772199;9561.187299079656;10487.895121048692;10928;10934.179936504794;8946.642134314554;8967.686130985385;8973.820523589491;9078.04680508537;9104.271658267322;7878.566678832604;8240.876386854203;9006.234470069987;9381.718548443558;9507.286953478517;3150.1966998415714;3548.520949671761;3696.2977034363385;3711.7958512282084;3719.4048952167523;862.9007664118707;867.5965675960708;871.5293741379584;964.4791812421214;1073.7476692977143;18943.715844262348;19203.90398048001;19356.516087097654;19705.408837734893;19837.801621983595;950.4629884115591;966.6036924860773;1002.4536627538009;1005.1305620638242;1024.5543188712934;10161.18710503158;10440.955904409499;10450.111740502143;10469.790604187874;10500.894991050145;554404;562415;566828;586838;593286;110802;111357;112758;112846;114507.42746286215;1015.7714514234239;1023.0945613132283;1051.7423231308378;1061.4798748613123;1067.4502631144967;17169.91415042917;17215.827841721424;17545.298188072335;17621.942683439036;17783.377581784574;34656.65343346635;38121.618783811806;48333.27500087572;48404;49924.50075499198;64665;65913;66264.33735662582;66899;68233;7905.960470197612;8031.759047228593;8173.019237691515;8190.788487108566;8330.333573314;11620.186586938833;11959.581414650445;12048.698782530157;12343.382830858602;12388;10553.472326383804;10631.787364252827;10675;10828.675139745821;10891.455427228448;9462.290328225274;9685.17675997548;10495.21285903545;10528.789424211625;10634;10673.199510036933;10701.625443109453;10832.458377081271;10945.398003109587;11038.944805275922;7848.332891704269;8020.919790802017;8078.878816817635;8158.551279679675;8168.673253070048;9282.164605185566;9837.458939758382;10782.245242832927;11072.612458564234;11087.667369978913 05:10:57 INFO - PROCESS | 1945 | |3;mootools.html;1423.4377420918731;1602.7916370871785;1603.2624992503445;1827.4974382044936;1927.9807201927986;7670.117936437305;8442.66229350826;8622.698205562807;8634.179752923463;8635.352348573866;8247.587620618975;8502.574871256427;8511.9785625725;8521.78695532612;8537.188967048136;2407.374082738483;2420.7942324902406;2429.2469334506272;2431.124795073772;2432.002878819684;2375.9168429104984;2403.71155461344;2424.963625545618;2430.161594249981;2437.3297343230574;2396.6524853896176;2410.517896420714;2423.079229892647;2426.4419183587784;2433.853968761871;2375.2043065573052;2394.6647469354302;2406.4946361264156;2407.3259487343485;2423.1947199336605;2369.620860662303;2397.6283676030243;2405.2663937499233;2407.434252950555;2407.9759202407927;8404.117567655368;8408.663653453854;8550.74347769568;8569.057403685589;8570.27152692022;5124.590032797368;5310.548603368679;5326.067938110854;5332.413434522199;5339.199120131972;3446.1212390840174;3508.6842184203288;3578.5169002184634;3599.8740044098595;3611.1874828163714;1037.7924415117006;1039.5581877701945;1045.9477026148738;1046.4767616191905;1052.6631477927067;11080.224384293102;11220.438978051065;11279.210455268134;11306.095512359074;11306.265092768943;4506.369108324837;4546.272596384629;4643.654954248083;4654.115718013567;4654.348391225231;8993.505357205417;9004.414713043761;9021.142991415805;9040.819183616291;9044.457332560067;5139.7687104080405;5185.118529849935;5214.687118772886;5218.921716174261;5237.240600112962;4168.270552653274;4349.064951035542;4498.482674492475;4806.831760888352;4910.017996400663;3750.4936833527127;3771.9434208486896;3832.291026160169;3863.5943225961432;3883.49514563105;1040.948641871715;1146.696125526736;1207.9516819327127;1219.676785651803;1221.6090850927797;801.8115742800437;829.8755186721919;842.1830824100613;874.6807415293372;891.4651209274513;5570.248016517799;5699.629524080921;5843.795467158629;5959.225300710967;6078.179445774852;449.2049073140523;462.9074185162981;465.9184642687516;472.50623098861087;475.25622400942416;3881.3013657541915;3925.3130702127014;3993.980030099889;4071.959280407158;4091.85678501251;10095.545700443497;10457.320274182153;10463.790724185626;10489.370637761758;10496.160307175407;10312.175025997902;10434;10445.321054131457;10451.954804519639;10460.476976151162;7723.7682869514;8038.758837234892;8051.798705032304;8067.394945379003;8089.514629122272;5671.6597004179885;5902.468777810018;5974.103884417198;6023.879522409615;6093.146959425772;9435;9658.323917325719;9821.263405244492;9841;9851;7967;9780.755481112745;9874.111329980336;9877.160441362565;10028.348157369748;6897.7240910365035;7154.463415243774;7297.963510182415;7503.587302698404;7648.847023059619;8161;8163.020437547532;8228.753137405887;8250.504969701837;8260.586970651564;7220.927790722026;7326.413886889142;7405.481616286808;7451.2176221495965;7501.099868015873;7204.605190833483;7324.523905946096;7363.15323737777;7388.445866559922;7467.813304667427;6995.055667485028;7063.399611032914;7076.469264805057;7086.574805511686;7098.538594991309;4797.991032808864;4905.3623029006;4932.506749325183;4951.702897826142;5108.208227724708;4865.610751139971;4937.703737775745;4959.876003099951;5044.167712327571;5182.844514664566 05:10:57 INFO - PROCESS | 1945 | |4;prototype.html;311.68519795007035;318.2219473387567;329.1786991456316;329.47448819133473;330.5702586637371;4115.615239589292;5002.624803139761;5003.749812509378;5294.444083371249;5306.938612277546;4420.690551661385;4603.424571928509;4992;5063.189889617653;5127.743612819363;3200.8239546824916;3390.745694072942;3563.5901871284746;4021.879343619696;4047.9999999999927;3420.6579342065843;3827.770333779968;4040.4545386372756;4053.7365071270274;4058.9594104059024;3316.0217735767887;3319.452290372086;3789.2610940866543;3907.609239076098;4059.6549293310104;3258.674132586746;3318.7179089777396;3581.104723819058;3999.6002398560922;4023.7384570002855;2957.704229577025;3141.6544180140168;3219.404410184123;3548.414511605582;3563.875264365761;4904.215325547895;5026.447090820006;5052.393712754493;5071.771770270347;5080.415752188472;4055.5538890721996;4605.4933957264675;4628.514006029386;4635.2583586626315;4645.442546894361;4109.958900411018;4766.737829419415;5454.427285135083;5462.808801691922;5491.835244942658;3045;3651.233241019389;3750.643688849555;3803.0492376905772;3976.1053762903116;4758.143534163849;5172.818951336722;5340;5430.755615997289;5851.853703657443;3341.9331613367594;3665.7272491055182;3789.9767062892865;4001.519817621846;4013.5986401359633;3853.903652408656;3915.4126880968083;4065.207284579538;4083.4895638045246;4085;4592.88517787058;4605.930911036337;4610.861674149781;4612.561806628365;4620.930686039712;4886.60907127429;5500;5586.849063110732;5601.92728690379;5603.094947717714;1368.8699573540498;1417.6951955329653;1467.5964109869872;1472.5508719840552;1494.521753039017;869.3349587565517;972.5672075926145;981.1415011864618;1015.8977509402381;1020.4591566469635;4765.666403351732;4993.6005119590345;5605.7197140142825;5617.494425501643;5656.236408084856;6181;6195.2255968004;6208.534359923024;6209.658468784261;6238.438540531374;5318.494742999409;5607.177667258157;5629.859253518613;5633.8168984512895;5637.267155269788;2735.972640273572;2872.6840047594746;3249.203945033482;3254.918627034343;3270.672932706758;5232.73836308183;5759.107338362561;6098.573099883054;6385.680715964183;6464.806055818333;5374.596905232123;5671.404502527175;6410.294867564564;6425.325340839145;6431.74273029083;5910.108765195182;6034.00438927572;6424.325445828121;6490.837729056811;6559.261795624068;5919;6189.84525386869;6195.513076861431;6204.286507051566;6218.289970258215;5277.498637629419;6017.428344307284;6092.725827337869;6120.44915957566;6133.907991380043;4962.42932062803;5448;6472.935270647234;6511.38141876521;6513.7068831903625;4261.531231564463;4285.314349704033;4289;4307.246231909466;4309.5259521453245;5227.843164705065;5422.427496026108;6145.016797312409;6250.781222657367;6308.369163083655;4694.694844835075;4726.976365118152;4848.975755121343;5290.470952904832;5606.915896261477;5315.495027972336;5520.723963801714;5538.86152846166;5540.7783688652;5612.943870561242;4837.854864354075;5039.765257511933;5364.7049412282695;5441.619086663895;5511.271920399428;5453.127499600045;5691.77232910679;5716.399778023248;5721.942780572141;5725.799597014085;4931.3096166536;5037.596992240538;5547.389787123494;5572.749226284908;5640.71796410186 05:10:57 INFO - PROCESS | 1945 | |5;yui.html;605.3088563631853;724.3046675191815;743.9739609113678;744.0699126092385;766.7201471462918;14061.929690351546;14540.345684444199;14548.854511454882;14559.781603275946;14612.707745845077;12485.937570312159;13234.735305293889;13486;13494.595162145128;13528.25594592297;904.2901322461869;911.3711539038043;913.5067063785548;913.6802119258256;915.716128000322;906.6690657909849;914.7301546043933;915.1260546178416;915.2083447817656;916.0107084348887;975.5658731864323;976.1068622210669;978.3885071830106;979.148141117229;980.1962390839515;791.7070683847013;888.8044630181382;898.6116450084635;907.9409838360518;976.2873102635081;724.7028718225555;725.6915810780397;727.2654618834962;727.8471520980547;731.6378392695597;12703.491860325576;12712.364381780873;12744.426500807485;12753.553625623152;12767.680807979781;11873.881261187364;11964.760704785942;11984;12096.999999999913;12485.93757031209;8378.790530236733;9736.853947190797;10325.173986081096;10357.482125893675;10389.688309350731;3455.3089382123676;3468.549088618489;3507.754457187998;3507.9123021924656;3540.4689296605457;52503.35689981284;67503;73279.90080148802;74504.8824267631;75849;9082.31882608807;9174.541272936327;9260;9609.231261499064;9700.27247956406;20908.268210612554;21099.68350474744;21124.049417776238;21137.471563211042;21197.576048478943;6047.78832740852;6336.6831658416895;7444.404447644176;7503.849923001619;7649.311561959339;4076.062505623723;4078.3678529827926;4156.231097247019;4173.603507666767;4256.553061928515;3553.5202747629255;3634.309481198564;3674.063113905937;3681.7975011374097;3686.8340924658455;640.5900223856736;645.9386358295955;651.2965996723527;654.1986067067804;661.1174082599783;539.8893226888479;580.7938181945349;599.6522017230084;618.7370367593754;632.8101569529123;8080.151584083704;8088.11030786613;8232.794180145544;8334.249917507454;8348.874766878502;522.8431470558817;556.9832905012856;580.8025271407741;597.5667640960269;614.2843587220927;3749.456328832304;3912.491376121086;3934.9803250984132;3953.2686453006286;4127.8968025798995;91969;92170;94615;98115;98604;75760.62119689476;76485.47029059388;77038.4592308162;77572;77681.61159194168;69945;70990;71327.93008104882;71400.64299678573;71980;6788.355106264897;7348.154962179204;8078.676852925817;8254.83490330178;8349.2485676287;40938;42117.78941105275;42286.788566056974;42704.932376690835;45201.773991129834;60646;60706.69646651739;61351.6932415335;62679;62714.05928911161;9569.856452153084;10599.364038157735;10814.513346899252;10837.078848297668;10898;11952.820707689565;12080.75838483243;12235.93882030584;12321.260724356567;12395.194312369667;11149.331040137618;11589.24669896454;11681.06551475897;11684.532618695157;11753.353565553976;11181.217314787888;11254.7749045017;11566.826497602711;11652.242604230698;11731.35477548743;10764.784704306026;10787.892121078688;10806.729831754268;10916.890831091587;10920.399378034284;9374.437533747998;9523.238140948846;9639.132478077006;9762.55522115726;9814.65648702292;9446.952765236405;9539.284553658365;9587.856182157413;9765.511724413893;9811.509424528602 05:10:57 INFO - PROCESS | 1945 | __end_tp_report 05:10:57 INFO - PROCESS | 1945 | __start_cc_report 05:10:57 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,1921 05:10:57 INFO - PROCESS | 1945 | __end_cc_report 05:10:57 INFO - PROCESS | 1945 | __startTimestamp1461759057239__endTimestamp 05:10:57 INFO - PROCESS | 1945 | 05:10:57 INFO - PROCESS | 1945 | ------- Summary: start ------- 05:10:57 INFO - PROCESS | 1945 | Number of tests: 6 05:10:57 INFO - PROCESS | 1945 | 05:10:57 INFO - PROCESS | 1945 | [#0] dojo.html Cycles:165 Average:5707.32 Median:5897.32 stddev:634.79 (10.8%) stddev-sans-first:609.58 05:10:57 INFO - PROCESS | 1945 | Values: 3365.9 3404.2 3423.1 3435.1 3483.7 5643.9 5710.6 5711.0 5716.1 5718.2 4781.3 5679.9 5709.4 5724.3 5825.6 5053.5 5076.6 5118.0 5131.7 5218.2 5188.5 5225.2 5249.2 5275.6 5309.2 5063.2 5153.7 5161.6 5171.4 5181.5 5107.7 5164.3 5175.9 5222.9 5259.5 5082.0 5135.8 5136.9 5140.6 5209.7 5615.6 5663.7 5671.3 5680.1 5753.7 6064.2 6108.7 6113.4 6115.5 6122.6 6228.1 6276.3 6313.7 6322.1 6329.9 5638.9 5782.9 5800.9 5803.1 5814.8 6199.4 6250.5 6283.5 6292.3 6297.3 6093.3 6094.1 6101.8 6132.3 6146.5 6130.5 6174.2 6259.9 6292.7 6303.1 6108.2 6109.9 6189.7 6233.7 6247.1 6176.5 6209.9 6216.6 6240.6 6258.4 4825.9 4888.1 4890.4 4893.8 4932.0 4387.6 4449.1 4481.7 4496.7 4504.1 5677.6 5699.3 5701.2 5701.4 5709.1 6229.3 6264.2 6286.4 6302.7 6304.3 5074.0 5622.4 5647.5 5698.9 5720.2 5400.1 5402.2 5405.6 5408.2 5409.5 6305.0 6309.4 6319.8 6328.5 6332.4 6136.8 6215.2 6314.8 6323.9 6328.1 6232.4 6289.4 6289.5 6298.5 6304.6 5949.0 6123.3 6141.9 6145.4 6157.2 4898.6 6144.6 6197.3 6295.1 6297.8 5839.5 5995.7 5996.0 5999.8 6003.2 5912.6 6006.1 6006.2 6016.3 6018.5 5842.6 5869.2 5890.2 5890.7 5897.3 5933.8 5998.9 6035.4 6046.0 6052.0 5896.8 5911.6 5914.3 5915.7 5920.4 05:10:57 INFO - PROCESS | 1945 | 05:10:57 INFO - PROCESS | 1945 | [#1] ext.html Cycles:180 Average:54974.73 Median:34333.33 stddev:105037.14 (305.9%) stddev-sans-first:105252.16 05:10:57 INFO - PROCESS | 1945 | Values: 498.3 502.9 509.8 511.7 588.5 29072.0 37174.6 38307.0 38339.5 38456.6 41788.6 42308.6 42571.8 42571.8 42576.6 8395.7 9333.0 9540.0 9557.7 9578.2 8412.3 11070.2 11328.0 11335.4 11345.7 9507.0 11395.5 11409.6 11410.7 11418.8 8292.2 8514.5 11274.6 11410.9 11433.3 8393.7 8496.4 11064.2 11292.0 11307.0 36893.6 37447.0 37462.8 37587.4 37718.4 3088.4 3252.4 3390.2 3441.4 3503.1 26942.7 27018.3 27130.6 27141.9 27279.9 2711.9 2736.3 2756.8 2786.9 2825.4 374013.0 375055.0 375256.0 376129.0 376375.0 60973.0 61073.4 61157.4 61159.7 61325.4 64234.7 64526.4 64581.4 64585.7 64652.4 15710.7 16164.5 16181.4 16235.7 16261.3 11304.0 11595.0 12360.8 12732.2 12820.4 16631.0 16863.9 16875.6 16911.3 16912.7 5201.3 5394.9 6310.0 6326.7 6356.8 1002.2 1014.8 1022.4 1078.1 1101.5 25457.5 28065.7 28318.7 28564.3 29285.0 1083.8 1101.5 1106.4 1119.5 1175.8 14881.9 14952.1 14980.4 15011.5 15127.0 446168.0 455134.0 456610.0 456661.0 456856.9 49967.8 55250.0 56262.7 57589.7 57619.4 1333.4 1350.2 1462.0 1479.5 1542.1 24119.7 27960.0 28209.3 28451.6 29295.0 48620.5 49025.0 49396.0 49433.8 49603.3 352912.0 355399.0 356267.0 356723.2 356804.0 30607.0 30783.8 36527.0 37893.4 38071.9 43839.8 44624.3 44785.6 44901.8 45755.8 33177.8 33555.0 35409.6 36155.8 36328.0 33983.2 34389.8 34433.7 35027.6 35280.0 33460.0 33922.7 34276.8 34922.7 35016.0 29556.7 30591.7 30652.4 30673.1 30818.8 22105.8 23614.6 23713.0 23746.9 24236.0 05:10:57 INFO - PROCESS | 1945 | 05:10:57 INFO - PROCESS | 1945 | [#2] jquery.html Cycles:180 Average:33750.46 Median:34793.56 stddev:95071.40 (273.2%) stddev-sans-first:95305.06 05:10:57 INFO - PROCESS | 1945 | Values: 371.2 393.7 398.2 400.5 403.3 14567.6 16224.6 16450.5 19549.8 20320.8 15608.6 17533.8 19192.9 19415.9 19759.0 5464.2 5731.9 5733.4 5769.4 5775.4 5456.3 5726.6 5740.2 5785.7 5795.6 5645.3 5664.4 5688.4 5688.8 5692.4 5663.2 5730.3 5731.8 5732.1 5739.6 4858.3 5028.2 5055.8 5059.2 5063.5 23847.5 24999.0 25408.0 25422.9 25441.5 1932.2 2077.9 2162.6 2465.3 2780.4 9199.7 9249.3 9515.7 9634.6 9803.4 1632.7 1759.1 1802.2 1804.0 1823.7 90001.1 96315.8 115548.4 124543.0 125262.0 16534.0 19614.6 20683.5 21031.7 21054.2 33955.0 34720.7 34746.8 34840.3 34913.1 9545.3 9561.2 10487.9 10928.0 10934.2 8946.6 8967.7 8973.8 9078.0 9104.3 7878.6 8240.9 9006.2 9381.7 9507.3 3150.2 3548.5 3696.3 3711.8 3719.4 862.9 867.6 871.5 964.5 1073.7 18943.7 19203.9 19356.5 19705.4 19837.8 950.5 966.6 1002.5 1005.1 1024.6 10161.2 10441.0 10450.1 10469.8 10500.9 554404.0 562415.0 566828.0 586838.0 593286.0 110802.0 111357.0 112758.0 112846.0 114507.4 1015.8 1023.1 1051.7 1061.5 1067.5 17169.9 17215.8 17545.3 17621.9 17783.4 34656.7 38121.6 48333.3 48404.0 49924.5 64665.0 65913.0 66264.3 66899.0 68233.0 7906.0 8031.8 8173.0 8190.8 8330.3 11620.2 11959.6 12048.7 12343.4 12388.0 10553.5 10631.8 10675.0 10828.7 10891.5 9462.3 9685.2 10495.2 10528.8 10634.0 10673.2 10701.6 10832.5 10945.4 11038.9 7848.3 8020.9 8078.9 8158.6 8168.7 9282.2 9837.5 10782.2 11072.6 11087.7 05:10:57 INFO - PROCESS | 1945 | 05:10:57 INFO - PROCESS | 1945 | [#3] mootools.html Cycles:180 Average:5581.36 Median:4944.70 stddev:3083.13 (62.4%) stddev-sans-first:3075.94 05:10:57 INFO - PROCESS | 1945 | Values: 1423.4 1602.8 1603.3 1827.5 1928.0 7670.1 8442.7 8622.7 8634.2 8635.4 8247.6 8502.6 8512.0 8521.8 8537.2 2407.4 2420.8 2429.2 2431.1 2432.0 2375.9 2403.7 2425.0 2430.2 2437.3 2396.7 2410.5 2423.1 2426.4 2433.9 2375.2 2394.7 2406.5 2407.3 2423.2 2369.6 2397.6 2405.3 2407.4 2408.0 8404.1 8408.7 8550.7 8569.1 8570.3 5124.6 5310.5 5326.1 5332.4 5339.2 3446.1 3508.7 3578.5 3599.9 3611.2 1037.8 1039.6 1045.9 1046.5 1052.7 11080.2 11220.4 11279.2 11306.1 11306.3 4506.4 4546.3 4643.7 4654.1 4654.3 8993.5 9004.4 9021.1 9040.8 9044.5 5139.8 5185.1 5214.7 5218.9 5237.2 4168.3 4349.1 4498.5 4806.8 4910.0 3750.5 3771.9 3832.3 3863.6 3883.5 1040.9 1146.7 1208.0 1219.7 1221.6 801.8 829.9 842.2 874.7 891.5 5570.2 5699.6 5843.8 5959.2 6078.2 449.2 462.9 465.9 472.5 475.3 3881.3 3925.3 3994.0 4072.0 4091.9 10095.5 10457.3 10463.8 10489.4 10496.2 10312.2 10434.0 10445.3 10452.0 10460.5 7723.8 8038.8 8051.8 8067.4 8089.5 5671.7 5902.5 5974.1 6023.9 6093.1 9435.0 9658.3 9821.3 9841.0 9851.0 7967.0 9780.8 9874.1 9877.2 10028.3 6897.7 7154.5 7298.0 7503.6 7648.8 8161.0 8163.0 8228.8 8250.5 8260.6 7220.9 7326.4 7405.5 7451.2 7501.1 7204.6 7324.5 7363.2 7388.4 7467.8 6995.1 7063.4 7076.5 7086.6 7098.5 4798.0 4905.4 4932.5 4951.7 5108.2 4865.6 4937.7 4959.9 5044.2 5182.8 05:10:57 INFO - PROCESS | 1945 | 05:10:57 INFO - PROCESS | 1945 | [#4] prototype.html Cycles:180 Average:4598.00 Median:5015.10 stddev:1470.40 (29.3%) stddev-sans-first:1438.89 05:10:57 INFO - PROCESS | 1945 | Values: 311.7 318.2 329.2 329.5 330.6 4115.6 5002.6 5003.7 5294.4 5306.9 4420.7 4603.4 4992.0 5063.2 5127.7 3200.8 3390.7 3563.6 4021.9 4048.0 3420.7 3827.8 4040.5 4053.7 4059.0 3316.0 3319.5 3789.3 3907.6 4059.7 3258.7 3318.7 3581.1 3999.6 4023.7 2957.7 3141.7 3219.4 3548.4 3563.9 4904.2 5026.4 5052.4 5071.8 5080.4 4055.6 4605.5 4628.5 4635.3 4645.4 4110.0 4766.7 5454.4 5462.8 5491.8 3045.0 3651.2 3750.6 3803.0 3976.1 4758.1 5172.8 5340.0 5430.8 5851.9 3341.9 3665.7 3790.0 4001.5 4013.6 3853.9 3915.4 4065.2 4083.5 4085.0 4592.9 4605.9 4610.9 4612.6 4620.9 4886.6 5500.0 5586.8 5601.9 5603.1 1368.9 1417.7 1467.6 1472.6 1494.5 869.3 972.6 981.1 1015.9 1020.5 4765.7 4993.6 5605.7 5617.5 5656.2 6181.0 6195.2 6208.5 6209.7 6238.4 5318.5 5607.2 5629.9 5633.8 5637.3 2736.0 2872.7 3249.2 3254.9 3270.7 5232.7 5759.1 6098.6 6385.7 6464.8 5374.6 5671.4 6410.3 6425.3 6431.7 5910.1 6034.0 6424.3 6490.8 6559.3 5919.0 6189.8 6195.5 6204.3 6218.3 5277.5 6017.4 6092.7 6120.4 6133.9 4962.4 5448.0 6472.9 6511.4 6513.7 4261.5 4285.3 4289.0 4307.2 4309.5 5227.8 5422.4 6145.0 6250.8 6308.4 4694.7 4727.0 4849.0 5290.5 5606.9 5315.5 5520.7 5538.9 5540.8 5612.9 4837.9 5039.8 5364.7 5441.6 5511.3 5453.1 5691.8 5716.4 5721.9 5725.8 4931.3 5037.6 5547.4 5572.7 5640.7 05:10:57 INFO - PROCESS | 1945 | 05:10:57 INFO - PROCESS | 1945 | [#5] yui.html Cycles:180 Average:17499.93 Median:616.51 stddev:24682.74 (4003.6%) stddev-sans-first:24719.38 05:10:57 INFO - PROCESS | 1945 | Values: 605.3 724.3 744.0 744.1 766.7 14061.9 14540.3 14548.9 14559.8 14612.7 12485.9 13234.7 13486.0 13494.6 13528.3 904.3 911.4 913.5 913.7 915.7 906.7 914.7 915.1 915.2 916.0 975.6 976.1 978.4 979.1 980.2 791.7 888.8 898.6 907.9 976.3 724.7 725.7 727.3 727.8 731.6 12703.5 12712.4 12744.4 12753.6 12767.7 11873.9 11964.8 11984.0 12097.0 12485.9 8378.8 9736.9 10325.2 10357.5 10389.7 3455.3 3468.5 3507.8 3507.9 3540.5 52503.4 67503.0 73279.9 74504.9 75849.0 9082.3 9174.5 9260.0 9609.2 9700.3 20908.3 21099.7 21124.0 21137.5 21197.6 6047.8 6336.7 7444.4 7503.8 7649.3 4076.1 4078.4 4156.2 4173.6 4256.6 3553.5 3634.3 3674.1 3681.8 3686.8 640.6 645.9 651.3 654.2 661.1 539.9 580.8 599.7 618.7 632.8 8080.2 8088.1 8232.8 8334.2 8348.9 522.8 557.0 580.8 597.6 614.3 3749.5 3912.5 3935.0 3953.3 4127.9 91969.0 92170.0 94615.0 98115.0 98604.0 75760.6 76485.5 77038.5 77572.0 77681.6 69945.0 70990.0 71327.9 71400.6 71980.0 6788.4 7348.2 8078.7 8254.8 8349.2 40938.0 42117.8 42286.8 42704.9 45201.8 60646.0 60706.7 61351.7 62679.0 62714.1 9569.9 10599.4 10814.5 10837.1 10898.0 11952.8 12080.8 12235.9 12321.3 12395.2 11149.3 11589.2 11681.1 11684.5 11753.4 11181.2 11254.8 11566.8 11652.2 11731.4 10764.8 10787.9 10806.7 10916.9 10920.4 9374.4 9523.2 9639.1 9762.6 9814.7 9447.0 9539.3 9587.9 9765.5 9811.5 05:10:57 INFO - PROCESS | 1945 | -------- Summary: end -------- 05:10:57 INFO - PROCESS | 1945 | 05:10:57 INFO - TEST-INFO | 1945: exit 0 05:10:57 INFO - TEST-OK | dromaeo_css | took 1115135ms 05:10:57 INFO - TEST-START | kraken 05:10:57 INFO - operating with platform_type : mac_ 05:10:57 INFO - Initialising browser for kraken test... 05:10:58 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpohMim7/profile http://localhost:51732/getInfo.html) 05:11:00 INFO - PROCESS | 1948 | __metrics Screen width/height:1600/1200 05:11:00 INFO - PROCESS | 1948 | colorDepth:24 05:11:00 INFO - PROCESS | 1948 | Browser inner width/height: 1024/689 05:11:00 INFO - PROCESS | 1948 | __metrics 05:11:00 INFO - PROCESS | 1948 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:11:00 ERROR - PROCESS | 1948 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:11:01 INFO - TEST-INFO | 1948: exit 0 05:11:01 INFO - Browser initialized. 05:11:01 INFO - Running cycle 1/1 for kraken test... 05:11:01 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpohMim7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:11:13 INFO - PROCESS | 1950 | RSS: Main: 239603712 05:11:13 INFO - PROCESS | 1950 | 05:11:15 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51732/tests/kraken/driver.html?testName=audio-beat-detection) 05:11:16 INFO - PROCESS | 1950 | RSS: Main: 287526912 05:11:16 INFO - PROCESS | 1950 | 05:11:19 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51732/tests/kraken/driver.html?testName=audio-dft) 05:11:19 INFO - PROCESS | 1950 | RSS: Main: 300552192 05:11:19 INFO - PROCESS | 1950 | 05:11:23 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51732/tests/kraken/driver.html?testName=audio-fft) 05:11:24 INFO - PROCESS | 1950 | RSS: Main: 388620288 05:11:24 INFO - PROCESS | 1950 | 05:11:26 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51732/tests/kraken/driver.html?testName=audio-oscillator) 05:11:26 INFO - PROCESS | 1950 | RSS: Main: 288014336 05:11:26 INFO - PROCESS | 1950 | 05:11:28 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51732/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:11:29 INFO - PROCESS | 1950 | RSS: Main: 293515264 05:11:29 INFO - PROCESS | 1950 | 05:11:32 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51732/tests/kraken/driver.html?testName=imaging-darkroom) 05:11:33 INFO - PROCESS | 1950 | RSS: Main: 330899456 05:11:33 INFO - PROCESS | 1950 | 05:11:35 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51732/tests/kraken/driver.html?testName=imaging-desaturate) 05:11:36 INFO - PROCESS | 1950 | RSS: Main: 332312576 05:11:36 INFO - PROCESS | 1950 | 05:11:39 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51732/tests/kraken/driver.html?testName=json-parse-financial) 05:11:39 INFO - PROCESS | 1950 | RSS: Main: 330133504 05:11:39 INFO - PROCESS | 1950 | 05:11:41 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51732/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:11:42 INFO - PROCESS | 1950 | RSS: Main: 322473984 05:11:42 INFO - PROCESS | 1950 | 05:11:43 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:11:44 INFO - PROCESS | 1950 | RSS: Main: 329289728 05:11:44 INFO - PROCESS | 1950 | 05:11:46 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:11:47 INFO - PROCESS | 1950 | RSS: Main: 331038720 05:11:47 INFO - PROCESS | 1950 | 05:11:50 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:11:50 INFO - PROCESS | 1950 | RSS: Main: 335859712 05:11:50 INFO - PROCESS | 1950 | 05:11:54 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:11:54 INFO - PROCESS | 1950 | RSS: Main: 333729792 05:11:54 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51732/tests/kraken/driver.html?testName=ai-astar) 05:11:56 INFO - PROCESS | 1950 | RSS: Main: 398852096 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | __start_tp_report 05:11:56 INFO - PROCESS | 1950 | _x_x_mozilla_page_load 05:11:56 INFO - PROCESS | 1950 | _x_x_mozilla_page_load_details 05:11:56 INFO - PROCESS | 1950 | |i|pagename|runs| 05:11:56 INFO - PROCESS | 1950 | |0;ai-astar;113;111;109;113;114;114;113;112;105;110 05:11:56 INFO - PROCESS | 1950 | |1;audio-beat-detection;161;122;144;170;116;139;140;132;210;119 05:11:56 INFO - PROCESS | 1950 | |2;audio-dft;187;189;188;188;184;184;186;185;187;186 05:11:56 INFO - PROCESS | 1950 | |3;audio-fft;78;72;89;95;78;87;85;97;89;72 05:11:56 INFO - PROCESS | 1950 | |4;audio-oscillator;90;101;138;79;81;81;126;79;77;127 05:11:56 INFO - PROCESS | 1950 | |5;imaging-gaussian-blur;88;84;88;85;87;84;89;129;125;84 05:11:56 INFO - PROCESS | 1950 | |6;imaging-darkroom;93;100;127;94;92;93;93;94;92;94 05:11:56 INFO - PROCESS | 1950 | |7;imaging-desaturate;85;86;87;87;87;85;85;85;84;98 05:11:56 INFO - PROCESS | 1950 | |8;json-parse-financial;60;62;60;58;58;58;58;77;59;57 05:11:56 INFO - PROCESS | 1950 | |9;json-stringify-tinderbox;62;62;67;77;70;71;67;67;68;69 05:11:56 INFO - PROCESS | 1950 | |10;stanford-crypto-aes;83;82;83;81;83;79;82;80;82;82 05:11:56 INFO - PROCESS | 1950 | |11;stanford-crypto-ccm;146;158;137;144;138;149;145;149;176;139 05:11:56 INFO - PROCESS | 1950 | |12;stanford-crypto-pbkdf2;196;196;198;201;231;270;227;194;210;239 05:11:56 INFO - PROCESS | 1950 | |13;stanford-crypto-sha256-iterative;68;68;69;70;71;81;103;98;101;75 05:11:56 INFO - PROCESS | 1950 | __end_tp_report 05:11:56 INFO - PROCESS | 1950 | __start_cc_report 05:11:56 INFO - PROCESS | 1950 | _x_x_mozilla_cycle_collect,2021 05:11:56 INFO - PROCESS | 1950 | __end_cc_report 05:11:56 INFO - PROCESS | 1950 | __startTimestamp1461759116981__endTimestamp 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | ------- Summary: start ------- 05:11:56 INFO - PROCESS | 1950 | Number of tests: 14 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | [#0] ai-astar Cycles:10 Average:111.40 Median:113.00 stddev:2.80 (2.5%) stddev-sans-first:2.91 05:11:56 INFO - PROCESS | 1950 | Values: 113.0 111.0 109.0 113.0 114.0 114.0 113.0 112.0 105.0 110.0 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | [#1] audio-beat-detection Cycles:10 Average:145.30 Median:142.00 stddev:28.64 (20.2%) stddev-sans-first:29.81 05:11:56 INFO - PROCESS | 1950 | Values: 161.0 122.0 144.0 170.0 116.0 139.0 140.0 132.0 210.0 119.0 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | [#2] audio-dft Cycles:10 Average:186.40 Median:187.00 stddev:1.71 (0.9%) stddev-sans-first:1.80 05:11:56 INFO - PROCESS | 1950 | Values: 187.0 189.0 188.0 188.0 184.0 184.0 186.0 185.0 187.0 186.0 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | [#3] audio-fft Cycles:10 Average:84.20 Median:88.00 stddev:8.88 (10.1%) stddev-sans-first:9.13 05:11:56 INFO - PROCESS | 1950 | Values: 78.0 72.0 89.0 95.0 78.0 87.0 85.0 97.0 89.0 72.0 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:79.00 stddev:23.66 (30.0%) stddev-sans-first:24.92 05:11:56 INFO - PROCESS | 1950 | Values: 90.0 101.0 138.0 79.0 81.0 81.0 126.0 79.0 77.0 127.0 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | [#5] imaging-gaussian-blur Cycles:10 Average:94.30 Median:86.00 stddev:17.36 (20.2%) stddev-sans-first:18.26 05:11:56 INFO - PROCESS | 1950 | Values: 88.0 84.0 88.0 85.0 87.0 84.0 89.0 129.0 125.0 84.0 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:93.00 stddev:10.72 (11.5%) stddev-sans-first:11.26 05:11:56 INFO - PROCESS | 1950 | Values: 93.0 100.0 127.0 94.0 92.0 93.0 93.0 94.0 92.0 94.0 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | [#7] imaging-desaturate Cycles:10 Average:86.90 Median:86.50 stddev:4.04 (4.7%) stddev-sans-first:4.23 05:11:56 INFO - PROCESS | 1950 | Values: 85.0 86.0 87.0 87.0 87.0 85.0 85.0 85.0 84.0 98.0 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | [#8] json-parse-financial Cycles:10 Average:60.70 Median:59.50 stddev:5.91 (9.9%) stddev-sans-first:6.26 05:11:56 INFO - PROCESS | 1950 | Values: 60.0 62.0 60.0 58.0 58.0 58.0 58.0 77.0 59.0 57.0 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.50 stddev:4.35 (6.3%) stddev-sans-first:4.03 05:11:56 INFO - PROCESS | 1950 | Values: 62.0 62.0 67.0 77.0 70.0 71.0 67.0 67.0 68.0 69.0 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | [#10] stanford-crypto-aes Cycles:10 Average:81.70 Median:82.00 stddev:1.34 (1.6%) stddev-sans-first:1.33 05:11:56 INFO - PROCESS | 1950 | Values: 83.0 82.0 83.0 81.0 83.0 79.0 82.0 80.0 82.0 82.0 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:147.50 stddev:11.63 (7.9%) stddev-sans-first:12.31 05:11:56 INFO - PROCESS | 1950 | Values: 146.0 158.0 137.0 144.0 138.0 149.0 145.0 149.0 176.0 139.0 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:216.20 Median:218.50 stddev:25.08 (11.5%) stddev-sans-first:25.51 05:11:56 INFO - PROCESS | 1950 | Values: 196.0 196.0 198.0 201.0 231.0 270.0 227.0 194.0 210.0 239.0 05:11:56 INFO - PROCESS | 1950 | 05:11:56 INFO - PROCESS | 1950 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.40 Median:70.50 stddev:14.56 (20.7%) stddev-sans-first:14.74 05:11:56 INFO - PROCESS | 1950 | Values: 68.0 68.0 69.0 70.0 71.0 81.0 103.0 98.0 101.0 75.0 05:11:56 INFO - PROCESS | 1950 | -------- Summary: end -------- 05:11:56 INFO - PROCESS | 1950 | 05:11:57 INFO - TEST-INFO | 1950: exit 0 05:11:57 INFO - TEST-OK | kraken | took 59789ms 05:11:57 INFO - SUITE-END | took 1174s 05:11:58 INFO - Completed test suite (00:19:35) 05:11:58 INFO - javascript benchmark 05:11:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3365.932681346373, 3404.2170300830826, 3423.1271025888386, 3435.1068722132236, 3483.738719596031, 5643.915341269874, 5710.600257981938, 5711.0, 5716.1425786131995, 5718.22803921471, 4781.306710526971, 5679.914801277984, 5709.400512946143, 5724.34170070442, 5825.563082768788, 5053.469385714502, 5076.593872490201, 5118.0019896120275, 5131.717755523445, 5218.1650935850275, 5188.533032027117, 5225.242339860718, 5249.238860365244, 5275.551578115865, 5309.150535914255, 5063.18988961768, 5153.742312884341, 5161.638685292031, 5171.405288391846, 5181.455947125554, 5107.693538387709, 5164.328637277167, 5175.922361164586, 5222.895542089175, 5259.474052594748, 5081.974590127063, 5135.8202462914, 5136.948630513722, 5140.563052140573, 5209.6874187548865, 5615.634983726086, 5663.660180389149, 5671.347795003586, 5680.11958146488, 5753.683547404891, 6064.241969753781, 6108.725107370179, 6113.358097399753, 6115.541334399938, 6122.611623597858, 6228.128062071313, 6276.309605943343, 6313.684315784192, 6322.083297921821, 6329.905051424233, 5638.915416268759, 5782.884342313214, 5800.8839823203725, 5803.071508558653, 5814.796482123148, 6199.380061993765, 6250.49996000328, 6283.5287353448675, 6292.339304373067, 6297.307296197415, 6093.268807743099, 6094.11635312886, 6101.84745381369, 6132.294786099566, 6146.477549408351, 6130.478909292671, 6174.197354343996, 6259.874802503924, 6292.716827742763, 6303.149074874925, 6108.190302030417, 6109.890546868077, 6189.690515474208, 6233.719482623293, 6247.094171345129, 6176.505879529627, 6209.9068513972325, 6216.595921265194, 6240.594361366497, 6258.43674069336, 4825.855224343275, 4888.120138375127, 4890.364252647133, 4893.779779909913, 4931.9753401232765, 4387.583179597933, 4449.065696203768, 4481.731096134177, 4496.662750293741, 4504.121696269196, 5677.630953987978, 5699.316082070095, 5701.173329867146, 5701.4013528579735, 5709.0865461525955, 6229.314775374705, 6264.1856558648, 6286.4342209201395, 6302.716377763011, 6304.306526282106, 5074.0, 5622.353429355595, 5647.548196144298, 5698.886022279614, 5720.170575266563, 5400.135978243462, 5402.216678581663, 5405.621606487587, 5408.242846001563, 5409.540189083972, 6305.0, 6309.432151106423, 6319.778807741707, 6328.46208072319, 6332.430081292706, 6136.7948077883175, 6215.223097112861, 6314.810555683337, 6323.936760632519, 6328.145700330397, 6232.407921247474, 6289.40250676185, 6289.49684025286, 6298.464630506275, 6304.621722696653, 5949.0, 6123.326434092157, 6141.938580614136, 6145.4161854623735, 6157.230346206724, 4898.608111351012, 6144.617461071223, 6197.25632924052, 6295.1186833842385, 6297.779577714758, 5839.5036421904615, 5995.700214989321, 5996.0, 5999.790007349897, 6003.159557661844, 5912.645241285537, 6006.129111278753, 6006.249218847644, 6016.308124565556, 6018.458338749533, 5842.620229685057, 5869.207656966255, 5890.175375447355, 5890.734916928663, 5897.321807992136, 5933.821985340447, 5998.850028749316, 6035.39646035393, 6046.0, 6052.03167493199, 5896.823095307148, 5911.615744976562, 5914.319853216935, 5915.733791979285, 5920.37836027211], "value": 5667.840122110229, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.31730529175036, 502.90444815485057, 509.8037255656572, 511.7338983728461, 588.4821357205658, 29072.0, 37174.62825371749, 38306.99999999996, 38339.46642134311, 38456.615433845654, 41788.58211417885, 42308.57691423085, 42571.787141064255, 42571.78714106433, 42576.57423425765, 8395.748127556182, 9333.0, 9540.0, 9557.665481708142, 9578.233741300679, 8412.284955778767, 11070.169737269736, 11327.98048175664, 11335.37655428951, 11345.716357091098, 9506.952465237664, 11395.54417823286, 11409.600663976762, 11410.657680269605, 11418.771624567546, 8292.212239837207, 8514.489130652119, 11274.605388811393, 11410.885891141148, 11433.256838305526, 8393.706220282322, 8496.405251632388, 11064.225504214708, 11291.983721465025, 11307.038901693366, 36893.63106368929, 37447.0, 37462.8126859364, 37587.43618845719, 37718.43422348667, 3088.4440800655875, 3252.447083995726, 3390.16940849491, 3441.4321636929876, 3503.1067077895227, 26942.730572694414, 27018.324541886413, 27130.5930411044, 27141.86429067862, 27279.863600682067, 2711.877965491538, 2736.315921019745, 2756.7656749176344, 2786.9442611147865, 2825.3784167483122, 374013.0, 375055.0, 375256.0, 376129.0, 376374.99999999726, 60973.0, 61073.38926610677, 61157.38842611606, 61159.694201528706, 61325.38674613286, 64234.67882660557, 64526.35473645297, 64581.3854653628, 64585.70828583402, 64652.35347646463, 15710.68578628421, 16164.464375884272, 16181.352745890268, 16235.675286494205, 16261.349546018027, 11304.039156671775, 11595.014423774073, 12360.75278494425, 12732.23606583608, 12820.423080961378, 16631.0, 16863.91568042152, 16875.57811054709, 16911.32354705798, 16912.661746764996, 5201.349831271091, 5394.9460505394445, 6310.021946598285, 6326.652034138075, 6356.760431715771, 1002.2433063037506, 1014.832552628808, 1022.3865680591734, 1078.1374900079904, 1101.4712937789907, 25457.490850182894, 28065.71934280631, 28318.71681283202, 28564.28589285243, 29285.0, 1083.7724077943562, 1101.509828126492, 1106.3804269608884, 1119.462657924201, 1175.8471398718284, 14881.925590371979, 14952.138255120264, 14980.40078396852, 15011.474598389223, 15127.01674391161, 446168.0, 455134.0, 456610.0, 456661.0, 456856.87287198857, 49967.75016124896, 55250.0, 56262.718686406304, 57589.71205143948, 57619.42380576224, 1333.4399552188104, 1350.1966330033615, 1461.9634509137356, 1479.5487376350102, 1542.0593438003034, 24119.67341796218, 27960.0, 28209.332343252318, 28451.573226401208, 29295.0, 48620.513794863014, 49025.01949961052, 49396.03623782584, 49433.76415589639, 49603.25595116004, 352912.0, 355399.0, 356267.0, 356723.21638391644, 356804.0, 30607.0, 30783.846080769454, 36527.0, 37893.431598525494, 38071.85784426472, 43839.78080109579, 44624.33063503985, 44785.55214447814, 44901.77549112234, 45755.77122114368, 33177.83411082929, 33555.0, 35409.64590354064, 36155.81922090373, 36328.0, 33983.15042123967, 34389.828050859585, 34433.655663443045, 35027.649723502436, 35280.0, 33459.99620011404, 33922.66077339195, 34276.828615856764, 34922.650773492955, 35016.0, 29556.704432955394, 30591.694083058883, 30652.386952260385, 30673.07980760581, 30818.84590577033, 22105.778942211015, 23614.645780312967, 23713.0, 23746.88126559356, 24236.030558777453], "value": 18939.094927506972, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.21488052768404, 393.7342293951582, 398.16584255983724, 400.5193767479026, 403.34859202388134, 14567.635809104751, 16224.594385140377, 16450.50648480546, 19549.804501954975, 20320.796792032073, 15608.609784755386, 17533.824661753377, 19192.94438805865, 19415.90292048538, 19759.0, 5464.23500709901, 5731.914021289663, 5733.426657334254, 5769.423057694218, 5775.364709881909, 5456.345238571357, 5726.570507211955, 5740.225069615611, 5785.739641716133, 5795.623284486516, 5645.266115405012, 5664.376918538978, 5688.374278829326, 5688.800891968782, 5692.402297758737, 5663.1505274208785, 5730.3123625165035, 5731.770729170828, 5732.054211055212, 5739.62692424991, 4858.271259311096, 5028.245763135523, 5055.823046193365, 5059.16523773577, 5063.468335824759, 23847.523049539086, 24999.0, 25408.0, 25422.87288563564, 25441.491170176494, 1932.2464238946823, 2077.885716285604, 2162.610730068587, 2465.3097132802664, 2780.402213524102, 9199.67801126964, 9249.306302027375, 9515.667806507094, 9634.614615415376, 9803.411795292233, 1632.6897889401096, 1759.13802236903, 1802.2430579156785, 1804.0, 1823.717323814811, 90001.09998900058, 96315.84999425043, 115548.42225788817, 124543.0, 125262.0, 16534.015918089906, 19614.607707846048, 20683.4829129273, 21031.68452473214, 21054.157833686786, 33955.0, 34720.652793471745, 34746.826265868505, 34840.30319393598, 34913.1271718204, 9545.331826772199, 9561.187299079656, 10487.895121048692, 10928.0, 10934.179936504794, 8946.642134314554, 8967.686130985385, 8973.820523589491, 9078.04680508537, 9104.271658267322, 7878.566678832604, 8240.876386854203, 9006.234470069987, 9381.718548443558, 9507.286953478517, 3150.1966998415714, 3548.520949671761, 3696.2977034363385, 3711.7958512282084, 3719.4048952167523, 862.9007664118707, 867.5965675960708, 871.5293741379584, 964.4791812421214, 1073.7476692977143, 18943.715844262348, 19203.90398048001, 19356.516087097654, 19705.408837734893, 19837.801621983595, 950.4629884115591, 966.6036924860773, 1002.4536627538009, 1005.1305620638242, 1024.5543188712934, 10161.18710503158, 10440.955904409499, 10450.111740502143, 10469.790604187874, 10500.894991050145, 554404.0, 562415.0, 566828.0, 586838.0, 593286.0, 110802.0, 111357.0, 112758.0, 112846.0, 114507.42746286215, 1015.7714514234239, 1023.0945613132283, 1051.7423231308378, 1061.4798748613123, 1067.4502631144967, 17169.91415042917, 17215.827841721424, 17545.298188072335, 17621.942683439036, 17783.377581784574, 34656.65343346635, 38121.618783811806, 48333.27500087572, 48404.0, 49924.50075499198, 64665.0, 65913.0, 66264.33735662582, 66899.0, 68233.0, 7905.960470197612, 8031.759047228593, 8173.019237691515, 8190.788487108566, 8330.333573314, 11620.186586938833, 11959.581414650445, 12048.698782530157, 12343.382830858602, 12388.0, 10553.472326383804, 10631.787364252827, 10675.0, 10828.675139745821, 10891.455427228448, 9462.290328225274, 9685.17675997548, 10495.21285903545, 10528.789424211625, 10634.0, 10673.199510036933, 10701.625443109453, 10832.458377081271, 10945.398003109587, 11038.944805275922, 7848.332891704269, 8020.919790802017, 8078.878816817635, 8158.551279679675, 8168.673253070048, 9282.164605185566, 9837.458939758382, 10782.245242832927, 11072.612458564234, 11087.667369978913], "value": 9959.681155001546, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.4377420918731, 1602.7916370871785, 1603.2624992503445, 1827.4974382044936, 1927.9807201927986, 7670.117936437305, 8442.66229350826, 8622.698205562807, 8634.179752923463, 8635.352348573866, 8247.587620618975, 8502.574871256427, 8511.9785625725, 8521.78695532612, 8537.188967048136, 2407.374082738483, 2420.7942324902406, 2429.2469334506272, 2431.124795073772, 2432.002878819684, 2375.9168429104984, 2403.71155461344, 2424.963625545618, 2430.161594249981, 2437.3297343230574, 2396.6524853896176, 2410.517896420714, 2423.079229892647, 2426.4419183587784, 2433.853968761871, 2375.2043065573052, 2394.6647469354302, 2406.4946361264156, 2407.3259487343485, 2423.1947199336605, 2369.620860662303, 2397.6283676030243, 2405.2663937499233, 2407.434252950555, 2407.9759202407927, 8404.117567655368, 8408.663653453854, 8550.74347769568, 8569.057403685589, 8570.27152692022, 5124.590032797368, 5310.548603368679, 5326.067938110854, 5332.413434522199, 5339.199120131972, 3446.1212390840174, 3508.6842184203288, 3578.5169002184634, 3599.8740044098595, 3611.1874828163714, 1037.7924415117006, 1039.5581877701945, 1045.9477026148738, 1046.4767616191905, 1052.6631477927067, 11080.224384293102, 11220.438978051065, 11279.210455268134, 11306.095512359074, 11306.265092768943, 4506.369108324837, 4546.272596384629, 4643.654954248083, 4654.115718013567, 4654.348391225231, 8993.505357205417, 9004.414713043761, 9021.142991415805, 9040.819183616291, 9044.457332560067, 5139.7687104080405, 5185.118529849935, 5214.687118772886, 5218.921716174261, 5237.240600112962, 4168.270552653274, 4349.064951035542, 4498.482674492475, 4806.831760888352, 4910.017996400663, 3750.4936833527127, 3771.9434208486896, 3832.291026160169, 3863.5943225961432, 3883.49514563105, 1040.948641871715, 1146.696125526736, 1207.9516819327127, 1219.676785651803, 1221.6090850927797, 801.8115742800437, 829.8755186721919, 842.1830824100613, 874.6807415293372, 891.4651209274513, 5570.248016517799, 5699.629524080921, 5843.795467158629, 5959.225300710967, 6078.179445774852, 449.2049073140523, 462.9074185162981, 465.9184642687516, 472.50623098861087, 475.25622400942416, 3881.3013657541915, 3925.3130702127014, 3993.980030099889, 4071.959280407158, 4091.85678501251, 10095.545700443497, 10457.320274182153, 10463.790724185626, 10489.370637761758, 10496.160307175407, 10312.175025997902, 10434.0, 10445.321054131457, 10451.954804519639, 10460.476976151162, 7723.7682869514, 8038.758837234892, 8051.798705032304, 8067.394945379003, 8089.514629122272, 5671.6597004179885, 5902.468777810018, 5974.103884417198, 6023.879522409615, 6093.146959425772, 9435.0, 9658.323917325719, 9821.263405244492, 9841.0, 9851.0, 7967.0, 9780.755481112745, 9874.111329980336, 9877.160441362565, 10028.348157369748, 6897.7240910365035, 7154.463415243774, 7297.963510182415, 7503.587302698404, 7648.847023059619, 8161.0, 8163.020437547532, 8228.753137405887, 8250.504969701837, 8260.586970651564, 7220.927790722026, 7326.413886889142, 7405.481616286808, 7451.2176221495965, 7501.099868015873, 7204.605190833483, 7324.523905946096, 7363.15323737777, 7388.445866559922, 7467.813304667427, 6995.055667485028, 7063.399611032914, 7076.469264805057, 7086.574805511686, 7098.538594991309, 4797.991032808864, 4905.3623029006, 4932.506749325183, 4951.702897826142, 5108.208227724708, 4865.610751139971, 4937.703737775745, 4959.876003099951, 5044.167712327571, 5182.844514664566], "value": 4437.2023981813, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.68519795007035, 318.2219473387567, 329.1786991456316, 329.47448819133473, 330.5702586637371, 4115.615239589292, 5002.624803139761, 5003.749812509378, 5294.444083371249, 5306.938612277546, 4420.690551661385, 4603.424571928509, 4992.0, 5063.189889617653, 5127.743612819363, 3200.8239546824916, 3390.745694072942, 3563.5901871284746, 4021.879343619696, 4047.9999999999927, 3420.6579342065843, 3827.770333779968, 4040.4545386372756, 4053.7365071270274, 4058.9594104059024, 3316.0217735767887, 3319.452290372086, 3789.2610940866543, 3907.609239076098, 4059.6549293310104, 3258.674132586746, 3318.7179089777396, 3581.104723819058, 3999.6002398560922, 4023.7384570002855, 2957.704229577025, 3141.6544180140168, 3219.404410184123, 3548.414511605582, 3563.875264365761, 4904.215325547895, 5026.447090820006, 5052.393712754493, 5071.771770270347, 5080.415752188472, 4055.5538890721996, 4605.4933957264675, 4628.514006029386, 4635.2583586626315, 4645.442546894361, 4109.958900411018, 4766.737829419415, 5454.427285135083, 5462.808801691922, 5491.835244942658, 3045.0, 3651.233241019389, 3750.643688849555, 3803.0492376905772, 3976.1053762903116, 4758.143534163849, 5172.818951336722, 5340.0, 5430.755615997289, 5851.853703657443, 3341.9331613367594, 3665.7272491055182, 3789.9767062892865, 4001.519817621846, 4013.5986401359633, 3853.903652408656, 3915.4126880968083, 4065.207284579538, 4083.4895638045246, 4085.0, 4592.88517787058, 4605.930911036337, 4610.861674149781, 4612.561806628365, 4620.930686039712, 4886.60907127429, 5500.0, 5586.849063110732, 5601.92728690379, 5603.094947717714, 1368.8699573540498, 1417.6951955329653, 1467.5964109869872, 1472.5508719840552, 1494.521753039017, 869.3349587565517, 972.5672075926145, 981.1415011864618, 1015.8977509402381, 1020.4591566469635, 4765.666403351732, 4993.6005119590345, 5605.7197140142825, 5617.494425501643, 5656.236408084856, 6181.0, 6195.2255968004, 6208.534359923024, 6209.658468784261, 6238.438540531374, 5318.494742999409, 5607.177667258157, 5629.859253518613, 5633.8168984512895, 5637.267155269788, 2735.972640273572, 2872.6840047594746, 3249.203945033482, 3254.918627034343, 3270.672932706758, 5232.73836308183, 5759.107338362561, 6098.573099883054, 6385.680715964183, 6464.806055818333, 5374.596905232123, 5671.404502527175, 6410.294867564564, 6425.325340839145, 6431.74273029083, 5910.108765195182, 6034.00438927572, 6424.325445828121, 6490.837729056811, 6559.261795624068, 5919.0, 6189.84525386869, 6195.513076861431, 6204.286507051566, 6218.289970258215, 5277.498637629419, 6017.428344307284, 6092.725827337869, 6120.44915957566, 6133.907991380043, 4962.42932062803, 5448.0, 6472.935270647234, 6511.38141876521, 6513.7068831903625, 4261.531231564463, 4285.314349704033, 4289.0, 4307.246231909466, 4309.5259521453245, 5227.843164705065, 5422.427496026108, 6145.016797312409, 6250.781222657367, 6308.369163083655, 4694.694844835075, 4726.976365118152, 4848.975755121343, 5290.470952904832, 5606.915896261477, 5315.495027972336, 5520.723963801714, 5538.86152846166, 5540.7783688652, 5612.943870561242, 4837.854864354075, 5039.765257511933, 5364.7049412282695, 5441.619086663895, 5511.271920399428, 5453.127499600045, 5691.77232910679, 5716.399778023248, 5721.942780572141, 5725.799597014085, 4931.3096166536, 5037.596992240538, 5547.389787123494, 5572.749226284908, 5640.71796410186], "value": 4149.620954571173, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.3088563631853, 724.3046675191815, 743.9739609113678, 744.0699126092385, 766.7201471462918, 14061.929690351546, 14540.345684444199, 14548.854511454882, 14559.781603275946, 14612.707745845077, 12485.937570312159, 13234.735305293889, 13486.0, 13494.595162145128, 13528.25594592297, 904.2901322461869, 911.3711539038043, 913.5067063785548, 913.6802119258256, 915.716128000322, 906.6690657909849, 914.7301546043933, 915.1260546178416, 915.2083447817656, 916.0107084348887, 975.5658731864323, 976.1068622210669, 978.3885071830106, 979.148141117229, 980.1962390839515, 791.7070683847013, 888.8044630181382, 898.6116450084635, 907.9409838360518, 976.2873102635081, 724.7028718225555, 725.6915810780397, 727.2654618834962, 727.8471520980547, 731.6378392695597, 12703.491860325576, 12712.364381780873, 12744.426500807485, 12753.553625623152, 12767.680807979781, 11873.881261187364, 11964.760704785942, 11984.0, 12096.999999999913, 12485.93757031209, 8378.790530236733, 9736.853947190797, 10325.173986081096, 10357.482125893675, 10389.688309350731, 3455.3089382123676, 3468.549088618489, 3507.754457187998, 3507.9123021924656, 3540.4689296605457, 52503.35689981284, 67503.0, 73279.90080148802, 74504.8824267631, 75849.0, 9082.31882608807, 9174.541272936327, 9260.0, 9609.231261499064, 9700.27247956406, 20908.268210612554, 21099.68350474744, 21124.049417776238, 21137.471563211042, 21197.576048478943, 6047.78832740852, 6336.6831658416895, 7444.404447644176, 7503.849923001619, 7649.311561959339, 4076.062505623723, 4078.3678529827926, 4156.231097247019, 4173.603507666767, 4256.553061928515, 3553.5202747629255, 3634.309481198564, 3674.063113905937, 3681.7975011374097, 3686.8340924658455, 640.5900223856736, 645.9386358295955, 651.2965996723527, 654.1986067067804, 661.1174082599783, 539.8893226888479, 580.7938181945349, 599.6522017230084, 618.7370367593754, 632.8101569529123, 8080.151584083704, 8088.11030786613, 8232.794180145544, 8334.249917507454, 8348.874766878502, 522.8431470558817, 556.9832905012856, 580.8025271407741, 597.5667640960269, 614.2843587220927, 3749.456328832304, 3912.491376121086, 3934.9803250984132, 3953.2686453006286, 4127.8968025798995, 91969.0, 92170.0, 94615.0, 98115.0, 98604.0, 75760.62119689476, 76485.47029059388, 77038.4592308162, 77572.0, 77681.61159194168, 69945.0, 70990.0, 71327.93008104882, 71400.64299678573, 71980.0, 6788.355106264897, 7348.154962179204, 8078.676852925817, 8254.83490330178, 8349.2485676287, 40938.0, 42117.78941105275, 42286.788566056974, 42704.932376690835, 45201.773991129834, 60646.0, 60706.69646651739, 61351.6932415335, 62679.0, 62714.05928911161, 9569.856452153084, 10599.364038157735, 10814.513346899252, 10837.078848297668, 10898.0, 11952.820707689565, 12080.75838483243, 12235.93882030584, 12321.260724356567, 12395.194312369667, 11149.331040137618, 11589.24669896454, 11681.06551475897, 11684.532618695157, 11753.353565553976, 11181.217314787888, 11254.7749045017, 11566.826497602711, 11652.242604230698, 11731.35477548743, 10764.784704306026, 10787.892121078688, 10806.729831754268, 10916.890831091587, 10920.399378034284, 9374.437533747998, 9523.238140948846, 9639.132478077006, 9762.55522115726, 9814.65648702292, 9446.952765236405, 9539.284553658365, 9587.856182157413, 9765.511724413893, 9811.509424528602], "value": 6753.477858052994, "unit": "score"}], "name": "dromaeo_css", "value": 7144.1753806531415, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 109.0, 113.0, 114.0, 114.0, 113.0, 112.0, 105.0, 110.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 122.0, 144.0, 170.0, 116.0, 139.0, 140.0, 132.0, 210.0, 119.0], "value": 145.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 189.0, 188.0, 188.0, 184.0, 184.0, 186.0, 185.0, 187.0, 186.0], "value": 186.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 89.0, 95.0, 78.0, 87.0, 85.0, 97.0, 89.0, 72.0], "value": 84.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 101.0, 138.0, 79.0, 81.0, 81.0, 126.0, 79.0, 77.0, 127.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 88.0, 85.0, 87.0, 84.0, 89.0, 129.0, 125.0, 84.0], "value": 94.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 100.0, 127.0, 94.0, 92.0, 93.0, 93.0, 94.0, 92.0, 94.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 87.0, 87.0, 87.0, 85.0, 85.0, 85.0, 84.0, 98.0], "value": 86.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 60.0, 58.0, 58.0, 58.0, 58.0, 77.0, 59.0, 57.0], "value": 60.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 67.0, 77.0, 70.0, 71.0, 67.0, 67.0, 68.0, 69.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 81.0, 83.0, 79.0, 82.0, 80.0, 82.0, 82.0], "value": 81.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 158.0, 137.0, 144.0, 138.0, 149.0, 145.0, 149.0, 176.0, 139.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 196.0, 198.0, 201.0, 231.0, 270.0, 227.0, 194.0, 210.0, 239.0], "value": 216.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 70.0, 71.0, 81.0, 103.0, 98.0, 101.0, 75.0], "value": 80.4, "unit": "score"}], "name": "kraken", "value": 1558.7000000000003, "alertThreshold": 2.0}]} 05:11:58 INFO - Return code: 0 05:11:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:11:58 ERROR - # TBPL SUCCESS # 05:11:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:11:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:11:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:11:58 INFO - 2016-04-27 05:11:58.300 screenresolution[1956:13310] starting screenresolution argv=screenresolution get 05:11:58 INFO - 2016-04-27 05:11:58.303 screenresolution[1956:13310] Display 0: 1600x1200x32@60 05:11:58 INFO - 2016-04-27 05:11:58.309 screenresolution[1957:13312] starting screenresolution argv=screenresolution list 05:11:58 INFO - Available Modes on Display 0 05:11:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:11:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:11:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:11:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:11:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:11:58 INFO - Intel Iris: 05:11:58 INFO - Chipset Model: Intel Iris 05:11:58 INFO - Type: GPU 05:11:58 INFO - Bus: Built-In 05:11:58 INFO - VRAM (Dynamic, Max): 1536 MB 05:11:58 INFO - Vendor: Intel (0x8086) 05:11:58 INFO - Device ID: 0x0a2e 05:11:58 INFO - Revision ID: 0x0009 05:11:58 INFO - Displays: 05:11:58 INFO - Display: 05:11:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:11:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:11:58 INFO - Display Serial Number: 1600x1200 60 05:11:58 INFO - Main Display: Yes 05:11:58 INFO - Mirror: Off 05:11:58 INFO - Online: Yes 05:11:58 INFO - Rotation: Supported 05:11:58 INFO - Return code: 0 05:11:58 INFO - Running post-action listener: _resource_record_post_action 05:11:58 INFO - Running post-run listener: _resource_record_post_run 05:11:59 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 512000; Write bytes: 694486016; Read time: 8; Write time: 2287 05:11:59 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 378335744; Write bytes: 372614656; Read time: 28212; Write time: 752 05:11:59 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 512000; Write bytes: 296496640; Read time: 8; Write time: 1483 05:11:59 INFO - Running post-run listener: _upload_blobber_files 05:11:59 INFO - Blob upload gear active. 05:11:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:11:59 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 05:11:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:12:00 INFO - (blobuploader) - INFO - Open directory for files ... 05:12:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:12:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:12:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:12:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:12:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:12:00 INFO - (blobuploader) - INFO - Done attempting. 05:12:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:12:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:12:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:12:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:12:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:12:01 INFO - (blobuploader) - INFO - Done attempting. 05:12:01 INFO - (blobuploader) - INFO - Iteration through files over. 05:12:01 INFO - Return code: 0 05:12:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:12:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:12:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211f74e82cef092d8d4110bd093d73be9a805f74bae48a7d82d18093a75dc880d03c814ec2eead955e31d0be34b99de19693a2e813c3351d1b9da3c28fd74fc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e498e8ade36964a58c9414cf9bc24c065c77c549cbe9ea9da1958afe379b7bcb0e33dfa69664f6f171dfb72df43037f95e26d56719023589dbc3b13c7761afee"} 05:12:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:12:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:12:01 INFO - Contents: 05:12:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211f74e82cef092d8d4110bd093d73be9a805f74bae48a7d82d18093a75dc880d03c814ec2eead955e31d0be34b99de19693a2e813c3351d1b9da3c28fd74fc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e498e8ade36964a58c9414cf9bc24c065c77c549cbe9ea9da1958afe379b7bcb0e33dfa69664f6f171dfb72df43037f95e26d56719023589dbc3b13c7761afee"} 05:12:01 INFO - Running post-run listener: copy_logs_to_upload_dir 05:12:01 INFO - Copying logs to upload dir... 05:12:01 INFO - mkdir: /builds/slave/test/build/upload/logs 05:12:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.529236 ========= master_lag: 2.53 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-27 05:12:04.495548) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-04-27 05:12:04.498518) ========= 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.HsXtgtBcPJ/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.g3LhpyNZDx/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211f74e82cef092d8d4110bd093d73be9a805f74bae48a7d82d18093a75dc880d03c814ec2eead955e31d0be34b99de19693a2e813c3351d1b9da3c28fd74fc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e498e8ade36964a58c9414cf9bc24c065c77c549cbe9ea9da1958afe379b7bcb0e33dfa69664f6f171dfb72df43037f95e26d56719023589dbc3b13c7761afee"} build_url:https://queue.taskcluster.net/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011390 build_url: 'https://queue.taskcluster.net/v1/task/AFrnqS_yQIyj0R0RnDORjw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211f74e82cef092d8d4110bd093d73be9a805f74bae48a7d82d18093a75dc880d03c814ec2eead955e31d0be34b99de19693a2e813c3351d1b9da3c28fd74fc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e498e8ade36964a58c9414cf9bc24c065c77c549cbe9ea9da1958afe379b7bcb0e33dfa69664f6f171dfb72df43037f95e26d56719023589dbc3b13c7761afee"}' ========= master_lag: 4.66 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-04-27 05:12:09.165920) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 05:12:09.166260) ========= 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.HsXtgtBcPJ/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.g3LhpyNZDx/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.005232 ========= master_lag: 0.86 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 05:12:10.027963) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 05:12:10.031279) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 05:12:10.032174) ========= ========= Total master_lag: 10.18 =========