builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0174 starttime: 1462798477.32 results: success (0) buildid: 20160509005049 builduid: 41dbd660830c4270b4520649f25740b3 revision: 307b5eca8726f6a72a1d69ba76630a6dbf402477 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:37.315492) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:37.315937) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:37.316209) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:37.334272) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:37.334596) ========= 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.IdoKgSnjyz/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.BVcwKUdqFG/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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-05-09 05:54:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 154M=0s 2016-05-09 05:54:37 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123500 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:37.476983) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:37.477374) ========= 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.IdoKgSnjyz/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.BVcwKUdqFG/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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.020108 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:37.526449) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-09 05:54:37.526809) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 307b5eca8726f6a72a1d69ba76630a6dbf402477 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 307b5eca8726f6a72a1d69ba76630a6dbf402477 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IdoKgSnjyz/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.BVcwKUdqFG/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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-05-09 05:54:37,601 truncating revision to first 12 chars 2016-05-09 05:54:37,601 Setting DEBUG logging. 2016-05-09 05:54:37,602 attempt 1/10 2016-05-09 05:54:37,602 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/307b5eca8726?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 05:54:39,764 unpacking tar archive at: mozilla-aurora-307b5eca8726/testing/mozharness/ program finished with exit code 0 elapsedTime=2.411504 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-09 05:54:39.955310) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:39.955651) ========= script_repo_revision: 307b5eca8726f6a72a1d69ba76630a6dbf402477 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:39.956030) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:39.956290) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:39.969312) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-05-09 05:54:39.969612) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IdoKgSnjyz/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.BVcwKUdqFG/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:54:40 INFO - MultiFileLogger online at 20160509 05:54:40 in /builds/slave/test 05:54:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:54:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:54:40 INFO - {'append_to_log': False, 05:54:40 INFO - 'base_work_dir': '/builds/slave/test', 05:54:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:54:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:54:40 INFO - 'branch': 'Mozilla-Aurora', 05:54:40 INFO - 'buildbot_json_path': 'buildprops.json', 05:54:40 INFO - 'config_files': ('talos/mac_config.py',), 05:54:40 INFO - 'default_actions': ('clobber', 05:54:40 INFO - 'read-buildbot-config', 05:54:40 INFO - 'download-and-extract', 05:54:40 INFO - 'populate-webroot', 05:54:40 INFO - 'create-virtualenv', 05:54:40 INFO - 'install', 05:54:40 INFO - 'run-tests'), 05:54:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:54:40 INFO - 'download_minidump_stackwalk': True, 05:54:40 INFO - 'download_symbols': 'ondemand', 05:54:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:54:40 INFO - 'tooltool.py': '/tools/tooltool.py', 05:54:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:54:40 INFO - '/tools/misc-python/virtualenv.py')}, 05:54:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:54:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:54:40 INFO - 'installer_path': 'installer.exe', 05:54:40 INFO - 'log_level': 'info', 05:54:40 INFO - 'log_name': 'talos', 05:54:40 INFO - 'log_to_console': True, 05:54:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:54:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:54:40 INFO - 'opt_config_files': (), 05:54:40 INFO - 'pip_index': False, 05:54:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:54:40 INFO - 'cmd': ('bash', 05:54:40 INFO - '-c', 05:54:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:54:40 INFO - 'enabled': True, 05:54:40 INFO - 'halt_on_failure': False, 05:54:40 INFO - 'name': 'check_screen_resolution'},), 05:54:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:54:40 INFO - 'cmd': ('bash', 05:54:40 INFO - '-c', 05:54:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:54:40 INFO - 'enabled': True, 05:54:40 INFO - 'halt_on_failure': False, 05:54:40 INFO - 'name': 'check_screen_resolution'},), 05:54:40 INFO - 'run_cmd_checks_enabled': True, 05:54:40 INFO - 'sps_profile': False, 05:54:40 INFO - 'sps_profile_interval': 0, 05:54:40 INFO - 'suite': 'dromaeojs-e10s', 05:54:40 INFO - 'system_bits': '32', 05:54:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:54:40 INFO - 'title': 't-yosemite-r7-0174', 05:54:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:54:40 INFO - 'use_talos_json': True, 05:54:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:54:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:54:40 INFO - 'work_dir': 'build'} 05:54:40 INFO - ##### 05:54:40 INFO - ##### Running clobber step. 05:54:40 INFO - ##### 05:54:40 INFO - Running pre-action listener: _resource_record_pre_action 05:54:40 INFO - Running main action method: clobber 05:54:40 INFO - rmtree: /builds/slave/test/build 05:54:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:54:41 INFO - Running post-action listener: _resource_record_post_action 05:54:41 INFO - ##### 05:54:41 INFO - ##### Running read-buildbot-config step. 05:54:41 INFO - ##### 05:54:41 INFO - Running pre-action listener: _resource_record_pre_action 05:54:41 INFO - Running main action method: read_buildbot_config 05:54:41 INFO - Using buildbot properties: 05:54:41 INFO - { 05:54:41 INFO - "project": "", 05:54:41 INFO - "product": "firefox", 05:54:41 INFO - "script_repo_revision": "production", 05:54:41 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 05:54:41 INFO - "repository": "", 05:54:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 05:54:41 INFO - "buildid": "20160509005049", 05:54:41 INFO - "pgo_build": "False", 05:54:41 INFO - "basedir": "/builds/slave/test", 05:54:41 INFO - "buildnumber": 4, 05:54:41 INFO - "slavename": "t-yosemite-r7-0174", 05:54:41 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:54:41 INFO - "platform": "yosemite_r7", 05:54:41 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:54:41 INFO - "branch": "mozilla-aurora", 05:54:41 INFO - "repo_path": "releases/mozilla-aurora", 05:54:41 INFO - "revision": "307b5eca8726f6a72a1d69ba76630a6dbf402477", 05:54:41 INFO - "stage_platform": "macosx64", 05:54:41 INFO - "builduid": "41dbd660830c4270b4520649f25740b3", 05:54:41 INFO - "slavebuilddir": "test" 05:54:41 INFO - } 05:54:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 05:54:41 INFO - Running post-action listener: _resource_record_post_action 05:54:41 INFO - ##### 05:54:41 INFO - ##### Running download-and-extract step. 05:54:41 INFO - ##### 05:54:41 INFO - Running pre-action listener: _resource_record_pre_action 05:54:41 INFO - Running main action method: download_and_extract 05:54:41 INFO - mkdir: /builds/slave/test/build/tests 05:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:41 INFO - https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:54:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:54:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:54:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 05:54:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 05:54:42 INFO - Downloaded 1199 bytes. 05:54:42 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 05:54:42 INFO - Using the following test package requirements: 05:54:42 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:54:42 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 05:54:42 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 05:54:42 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'jsshell-mac.zip'], 05:54:42 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 05:54:42 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:54:42 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 05:54:42 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 05:54:42 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 05:54:42 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 05:54:42 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:42 INFO - https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:54:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:54:43 INFO - Downloaded 18088795 bytes. 05:54:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:54:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:54:46 INFO - Return code: 0 05:54:46 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 05:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:46 INFO - https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:54:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:54:47 INFO - Downloaded 18088795 bytes. 05:54:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:54:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:54:51 INFO - Return code: 0 05:54:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:51 INFO - https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:54:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:54:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:54:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:54:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:54:52 INFO - Downloaded 10840145 bytes. 05:54:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:54:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:54:52 INFO - Return code: 0 05:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:52 INFO - https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 05:54:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:54:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:54:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 05:54:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 05:54:55 INFO - Downloaded 114085322 bytes. 05:54:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:54:55 INFO - mkdir: /builds/slave/test/properties 05:54:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:54:55 INFO - Writing to file /builds/slave/test/properties/build_url 05:54:55 INFO - Contents: 05:54:55 INFO - build_url:https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:54:56 INFO - Running post-action listener: _resource_record_post_action 05:54:56 INFO - Running post-action listener: set_extra_try_arguments 05:54:56 INFO - ##### 05:54:56 INFO - ##### Running populate-webroot step. 05:54:56 INFO - ##### 05:54:56 INFO - Running pre-action listener: _resource_record_pre_action 05:54:56 INFO - Running main action method: populate_webroot 05:54:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:54:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:54:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:54:56 INFO - '--tppagecycles', 05:54:56 INFO - '7'], 05:54:56 INFO - 'tests': ['tsvgm']}}, 05:54:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:54:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:54:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:54:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:54:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:54:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:54:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:56 INFO - 'talos_options': ['--disable-e10s'], 05:54:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:56 INFO - 'talos_options': ['--disable-e10s'], 05:54:56 INFO - 'tests': ['damp', 'tps']}, 05:54:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:56 INFO - 'tests': ['damp', 'tps']}, 05:54:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:54:56 INFO - 'tests': ['dromaeo_dom']}, 05:54:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:54:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:54:56 INFO - 'tests': ['a11yr', 05:54:56 INFO - 'ts_paint', 05:54:56 INFO - 'tpaint', 05:54:56 INFO - 'sessionrestore', 05:54:56 INFO - 'sessionrestore_no_auto_restore', 05:54:56 INFO - 'tabpaint']}, 05:54:56 INFO - 'other-e10s': {'tests': ['a11yr', 05:54:56 INFO - 'ts_paint', 05:54:56 INFO - 'tpaint', 05:54:56 INFO - 'sessionrestore', 05:54:56 INFO - 'sessionrestore_no_auto_restore', 05:54:56 INFO - 'tabpaint']}, 05:54:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:54:56 INFO - 'ts_paint', 05:54:56 INFO - 'tpaint', 05:54:56 INFO - 'sessionrestore', 05:54:56 INFO - 'sessionrestore_no_auto_restore', 05:54:56 INFO - 'tabpaint']}, 05:54:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:54:56 INFO - 'ts_paint', 05:54:56 INFO - 'tpaint', 05:54:56 INFO - 'sessionrestore', 05:54:56 INFO - 'sessionrestore_no_auto_restore', 05:54:56 INFO - 'tabpaint']}, 05:54:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:54:56 INFO - 'tests': ['a11yr', 05:54:56 INFO - 'ts_paint', 05:54:56 INFO - 'tpaint', 05:54:56 INFO - 'sessionrestore', 05:54:56 INFO - 'sessionrestore_no_auto_restore', 05:54:56 INFO - 'tabpaint']}, 05:54:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:54:56 INFO - 'tests': ['a11yr', 05:54:56 INFO - 'ts_paint', 05:54:56 INFO - 'tpaint', 05:54:56 INFO - 'sessionrestore', 05:54:56 INFO - 'sessionrestore_no_auto_restore', 05:54:56 INFO - 'tabpaint']}, 05:54:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:54:56 INFO - 'tests': ['tsvgx', 05:54:56 INFO - 'tsvgr_opacity', 05:54:56 INFO - 'tart', 05:54:56 INFO - 'tscrollx', 05:54:56 INFO - 'cart']}, 05:54:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:54:56 INFO - 'tsvgr_opacity', 05:54:56 INFO - 'tart', 05:54:56 INFO - 'tscrollx', 05:54:56 INFO - 'cart']}, 05:54:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:56 INFO - 'talos_options': ['--disable-e10s'], 05:54:56 INFO - 'tests': ['tp5o']}, 05:54:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:56 INFO - 'tests': ['tp5o']}, 05:54:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:56 INFO - 'talos_options': ['--disable-e10s', 05:54:56 INFO - '--xperf_path', 05:54:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:56 INFO - 'tests': ['tp5n']}, 05:54:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:56 INFO - 'talos_options': ['--xperf_path', 05:54:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:56 INFO - 'tests': ['tp5n']}}, 05:54:56 INFO - 'talos.zip': {'path': '', 05:54:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:54:56 INFO - Running post-action listener: _resource_record_post_action 05:54:56 INFO - ##### 05:54:56 INFO - ##### Running create-virtualenv step. 05:54:56 INFO - ##### 05:54:56 INFO - Running pre-action listener: _resource_record_pre_action 05:54:56 INFO - Running main action method: create_virtualenv 05:54:56 INFO - Creating virtualenv /builds/slave/test/build/venv 05:54:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:54:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:54:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:54:57 INFO - Using real prefix '/tools/python27' 05:54:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:54:57 INFO - Installing distribute.............................................................................................................................................................................................done. 05:55:00 INFO - Installing pip.................done. 05:55:00 INFO - Return code: 0 05:55:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:00 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 0x10acf3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8921498110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab92e00>, '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 0x10a9fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8db20>, '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.IdoKgSnjyz/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.BVcwKUdqFG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:55:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:55:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:55:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IdoKgSnjyz/Render', 05:55:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:00 INFO - 'HOME': '/Users/cltbld', 05:55:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:00 INFO - 'LOGNAME': 'cltbld', 05:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:00 INFO - 'MOZ_NO_REMOTE': '1', 05:55:00 INFO - 'NO_EM_RESTART': '1', 05:55:00 INFO - 'PAGER': '/bin/cat', 05:55:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:00 INFO - 'PWD': '/builds/slave/test', 05:55:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:00 INFO - 'SHELL': '/bin/bash', 05:55:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVcwKUdqFG/Listeners', 05:55:00 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 05:55:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:00 INFO - 'USER': 'cltbld', 05:55:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:00 INFO - 'XPC_FLAGS': '0x0', 05:55:00 INFO - 'XPC_SERVICE_NAME': '0', 05:55:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:55:00 INFO - Cleaning up... 05:55:00 INFO - Return code: 0 05:55:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:00 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 0x10acf3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8921498110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab92e00>, '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 0x10a9fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8db20>, '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.IdoKgSnjyz/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.BVcwKUdqFG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:55:00 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 05:55:00 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 05:55:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IdoKgSnjyz/Render', 05:55:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:00 INFO - 'HOME': '/Users/cltbld', 05:55:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:00 INFO - 'LOGNAME': 'cltbld', 05:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:00 INFO - 'MOZ_NO_REMOTE': '1', 05:55:00 INFO - 'NO_EM_RESTART': '1', 05:55:00 INFO - 'PAGER': '/bin/cat', 05:55:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:00 INFO - 'PWD': '/builds/slave/test', 05:55:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:00 INFO - 'SHELL': '/bin/bash', 05:55:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVcwKUdqFG/Listeners', 05:55:00 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 05:55:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:00 INFO - 'USER': 'cltbld', 05:55:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:00 INFO - 'XPC_FLAGS': '0x0', 05:55:00 INFO - 'XPC_SERVICE_NAME': '0', 05:55:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:01 INFO - Downloading/unpacking psutil>=0.7.1 05:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:55:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:55:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:55:04 INFO - Installing collected packages: psutil 05:55:04 INFO - Running setup.py install for psutil 05:55:04 INFO - building 'psutil._psutil_osx' extension 05:55:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:55:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:55:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:55:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:55:04 INFO - building 'psutil._psutil_posix' extension 05:55:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:55:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:55:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:55:04 INFO - ^ 05:55:04 INFO - 1 warning generated. 05:55:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:55:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:55:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:55:05 INFO - Successfully installed psutil 05:55:05 INFO - Cleaning up... 05:55:05 INFO - Return code: 0 05:55:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:05 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 0x10acf3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8921498110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab92e00>, '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 0x10a9fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8db20>, '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.IdoKgSnjyz/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.BVcwKUdqFG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:55:05 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 05:55:05 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 05:55:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IdoKgSnjyz/Render', 05:55:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:05 INFO - 'HOME': '/Users/cltbld', 05:55:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:05 INFO - 'LOGNAME': 'cltbld', 05:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:05 INFO - 'MOZ_NO_REMOTE': '1', 05:55:05 INFO - 'NO_EM_RESTART': '1', 05:55:05 INFO - 'PAGER': '/bin/cat', 05:55:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:05 INFO - 'PWD': '/builds/slave/test', 05:55:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:05 INFO - 'SHELL': '/bin/bash', 05:55:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVcwKUdqFG/Listeners', 05:55:05 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 05:55:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:05 INFO - 'USER': 'cltbld', 05:55:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:05 INFO - 'XPC_FLAGS': '0x0', 05:55:05 INFO - 'XPC_SERVICE_NAME': '0', 05:55:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:55:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:55:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:55:07 INFO - Installing collected packages: mozsystemmonitor 05:55:07 INFO - Running setup.py install for mozsystemmonitor 05:55:08 INFO - Successfully installed mozsystemmonitor 05:55:08 INFO - Cleaning up... 05:55:08 INFO - Return code: 0 05:55:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:55:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:08 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 0x10acf3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8921498110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab92e00>, '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 0x10a9fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8db20>, '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.IdoKgSnjyz/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.BVcwKUdqFG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:55:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:55:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IdoKgSnjyz/Render', 05:55:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:08 INFO - 'HOME': '/Users/cltbld', 05:55:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:08 INFO - 'LOGNAME': 'cltbld', 05:55:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:08 INFO - 'MOZ_NO_REMOTE': '1', 05:55:08 INFO - 'NO_EM_RESTART': '1', 05:55:08 INFO - 'PAGER': '/bin/cat', 05:55:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:08 INFO - 'PWD': '/builds/slave/test', 05:55:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:08 INFO - 'SHELL': '/bin/bash', 05:55:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVcwKUdqFG/Listeners', 05:55:08 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 05:55:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:08 INFO - 'USER': 'cltbld', 05:55:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:08 INFO - 'XPC_FLAGS': '0x0', 05:55:08 INFO - 'XPC_SERVICE_NAME': '0', 05:55:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:08 INFO - Downloading/unpacking blobuploader==1.2.4 05:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:10 INFO - Downloading blobuploader-1.2.4.tar.gz 05:55:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:55:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:55:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:11 INFO - Downloading docopt-0.6.1.tar.gz 05:55:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:55:12 INFO - Installing collected packages: blobuploader, requests, docopt 05:55:12 INFO - Running setup.py install for blobuploader 05:55:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:55:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:55:12 INFO - Running setup.py install for requests 05:55:12 INFO - Running setup.py install for docopt 05:55:12 INFO - Successfully installed blobuploader requests docopt 05:55:12 INFO - Cleaning up... 05:55:12 INFO - Return code: 0 05:55:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:55:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:12 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 0x10acf3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8921498110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab92e00>, '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 0x10a9fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8db20>, '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.IdoKgSnjyz/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.BVcwKUdqFG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:55:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:55:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:55:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IdoKgSnjyz/Render', 05:55:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:12 INFO - 'HOME': '/Users/cltbld', 05:55:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:12 INFO - 'LOGNAME': 'cltbld', 05:55:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:12 INFO - 'MOZ_NO_REMOTE': '1', 05:55:12 INFO - 'NO_EM_RESTART': '1', 05:55:12 INFO - 'PAGER': '/bin/cat', 05:55:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:12 INFO - 'PWD': '/builds/slave/test', 05:55:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:12 INFO - 'SHELL': '/bin/bash', 05:55:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVcwKUdqFG/Listeners', 05:55:12 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 05:55:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:12 INFO - 'USER': 'cltbld', 05:55:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:12 INFO - 'XPC_FLAGS': '0x0', 05:55:12 INFO - 'XPC_SERVICE_NAME': '0', 05:55:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:55:13 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-FuWAMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:55:13 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-h8M_Ge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:55:13 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-CWGqjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:55:13 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-UoyUDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:55:13 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-2YOnQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:55:13 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-4Z7ig8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:55:13 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-NdIHMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:55:13 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-820SOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:55:14 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-4FOBt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:55:14 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-ElEb_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:55:14 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-0W5HOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:55:14 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-6TZrys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:55:14 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-OFqyCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:55:14 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-DEn64c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:14 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-HHv62Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:55:14 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-5ylji9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:55:14 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-ZvKp72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:55:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:55:14 INFO - Running setup.py install for manifestparser 05:55:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:55:15 INFO - Running setup.py install for mozcrash 05:55:15 INFO - Running setup.py install for mozdebug 05:55:15 INFO - Running setup.py install for mozdevice 05:55:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:55:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:55:15 INFO - Running setup.py install for mozfile 05:55:15 INFO - Running setup.py install for mozhttpd 05:55:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:55:15 INFO - Running setup.py install for mozinfo 05:55:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:55:15 INFO - Running setup.py install for mozInstall 05:55:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Running setup.py install for mozleak 05:55:16 INFO - Running setup.py install for mozlog 05:55:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Running setup.py install for moznetwork 05:55:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Running setup.py install for mozprocess 05:55:16 INFO - Running setup.py install for mozprofile 05:55:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Running setup.py install for mozrunner 05:55:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:55:17 INFO - Running setup.py install for mozscreenshot 05:55:17 INFO - Running setup.py install for moztest 05:55:17 INFO - Running setup.py install for mozversion 05:55:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:55:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:55:17 INFO - Cleaning up... 05:55:17 INFO - Return code: 0 05:55:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:55: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')]} 05:55:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55: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')]} 05:55:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:17 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 0x10acf3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8921498110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab92e00>, '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 0x10a9fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8db20>, '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.IdoKgSnjyz/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.BVcwKUdqFG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:55:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:55:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:55:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IdoKgSnjyz/Render', 05:55:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:17 INFO - 'HOME': '/Users/cltbld', 05:55:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:17 INFO - 'LOGNAME': 'cltbld', 05:55:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:17 INFO - 'MOZ_NO_REMOTE': '1', 05:55:17 INFO - 'NO_EM_RESTART': '1', 05:55:17 INFO - 'PAGER': '/bin/cat', 05:55:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:17 INFO - 'PWD': '/builds/slave/test', 05:55:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:17 INFO - 'SHELL': '/bin/bash', 05:55:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVcwKUdqFG/Listeners', 05:55:17 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 05:55:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:17 INFO - 'USER': 'cltbld', 05:55:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:17 INFO - 'XPC_FLAGS': '0x0', 05:55:17 INFO - 'XPC_SERVICE_NAME': '0', 05:55:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:55:17 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-Wz5bBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:55:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:55:17 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-fX30me-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:55:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-L8Lzs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:55:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-yzHtai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:55:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-5p1KZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:55:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-BwmtDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:55:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-AYsD5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:55:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-qkLmm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:55:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-sjg7na-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:55:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-ydf7Vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:55:18 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)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:55:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-tphNoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:55:19 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-9c7jvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:55:19 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)) 05:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:55:19 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-LrSNsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:55:19 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-9i0o0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:55:19 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)) 05:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:19 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-GTnI_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:55:19 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-SKqezq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:55:19 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-cn0csw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:55:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:55:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:22 INFO - Downloading blessings-1.6.tar.gz 05:55:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:55:22 INFO - Installing collected packages: blessings 05:55:22 INFO - Running setup.py install for blessings 05:55:22 INFO - Successfully installed blessings 05:55:22 INFO - Cleaning up... 05:55:22 INFO - Return code: 0 05:55:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:55:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:55:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:55:22 INFO - Reading from file tmpfile_stdout 05:55:22 INFO - Current package versions: 05:55:22 INFO - blessings == 1.6 05:55:22 INFO - blobuploader == 1.2.4 05:55:22 INFO - docopt == 0.6.1 05:55:22 INFO - manifestparser == 1.1 05:55:22 INFO - mozInstall == 1.12 05:55:22 INFO - mozcrash == 0.17 05:55:22 INFO - mozdebug == 0.1 05:55:22 INFO - mozdevice == 0.48 05:55:22 INFO - mozfile == 1.2 05:55:22 INFO - mozhttpd == 0.7 05:55:22 INFO - mozinfo == 0.9 05:55:22 INFO - mozleak == 0.1 05:55:22 INFO - mozlog == 3.1 05:55:22 INFO - moznetwork == 0.27 05:55:22 INFO - mozprocess == 0.22 05:55:22 INFO - mozprofile == 0.28 05:55:22 INFO - mozrunner == 6.11 05:55:22 INFO - mozscreenshot == 0.1 05:55:22 INFO - mozsystemmonitor == 0.0 05:55:22 INFO - moztest == 0.7 05:55:22 INFO - mozversion == 1.4 05:55:22 INFO - psutil == 3.1.1 05:55:22 INFO - requests == 1.2.3 05:55:22 INFO - wsgiref == 0.1.2 05:55:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:22 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 0x10acf3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8921498110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab92e00>, '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 0x10a9fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8db20>, '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.IdoKgSnjyz/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.BVcwKUdqFG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:55:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:55:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:55:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IdoKgSnjyz/Render', 05:55:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:22 INFO - 'HOME': '/Users/cltbld', 05:55:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:22 INFO - 'LOGNAME': 'cltbld', 05:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:22 INFO - 'MOZ_NO_REMOTE': '1', 05:55:22 INFO - 'NO_EM_RESTART': '1', 05:55:22 INFO - 'PAGER': '/bin/cat', 05:55:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:22 INFO - 'PWD': '/builds/slave/test', 05:55:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:22 INFO - 'SHELL': '/bin/bash', 05:55:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVcwKUdqFG/Listeners', 05:55:22 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 05:55:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:22 INFO - 'USER': 'cltbld', 05:55:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:22 INFO - 'XPC_FLAGS': '0x0', 05:55:22 INFO - 'XPC_SERVICE_NAME': '0', 05:55:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:55:22 INFO - Cleaning up... 05:55:22 INFO - Return code: 0 05:55:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:22 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 0x10acf3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8921498110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab92e00>, '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 0x10a9fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8db20>, '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.IdoKgSnjyz/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.BVcwKUdqFG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:55:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:55:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:55:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IdoKgSnjyz/Render', 05:55:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:22 INFO - 'HOME': '/Users/cltbld', 05:55:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:22 INFO - 'LOGNAME': 'cltbld', 05:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:22 INFO - 'MOZ_NO_REMOTE': '1', 05:55:22 INFO - 'NO_EM_RESTART': '1', 05:55:22 INFO - 'PAGER': '/bin/cat', 05:55:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:22 INFO - 'PWD': '/builds/slave/test', 05:55:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:22 INFO - 'SHELL': '/bin/bash', 05:55:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVcwKUdqFG/Listeners', 05:55:22 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 05:55:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:22 INFO - 'USER': 'cltbld', 05:55:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:22 INFO - 'XPC_FLAGS': '0x0', 05:55:22 INFO - 'XPC_SERVICE_NAME': '0', 05:55:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:23 INFO - Downloading/unpacking jsonschema 05:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:55:25 INFO - Downloading/unpacking functools32 (from jsonschema) 05:55: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 05:55: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 05:55: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 05:55: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 05:55: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 05:55: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 05:55:25 INFO - Downloading functools32-3.2.3-2.tar.gz 05:55:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:55:26 INFO - warning: no files found matching '*.txt' 05:55:26 INFO - no previously-included directories found matching 'build' 05:55:26 INFO - no previously-included directories found matching 'dist' 05:55:26 INFO - no previously-included directories found matching '.git*' 05:55:26 INFO - Installing collected packages: jsonschema, functools32 05:55:26 INFO - Running setup.py install for functools32 05:55:26 INFO - warning: no files found matching '*.txt' 05:55:26 INFO - no previously-included directories found matching 'build' 05:55:26 INFO - no previously-included directories found matching 'dist' 05:55:26 INFO - no previously-included directories found matching '.git*' 05:55:26 INFO - Successfully installed jsonschema functools32 05:55:26 INFO - Cleaning up... 05:55:26 INFO - Return code: 0 05:55:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:55:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:26 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 0x10acf3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8921498110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab92e00>, '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 0x10a9fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8db20>, '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.IdoKgSnjyz/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.BVcwKUdqFG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:55:26 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 05:55:26 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 05:55:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IdoKgSnjyz/Render', 05:55:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:26 INFO - 'HOME': '/Users/cltbld', 05:55:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:26 INFO - 'LOGNAME': 'cltbld', 05:55:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:26 INFO - 'MOZ_NO_REMOTE': '1', 05:55:26 INFO - 'NO_EM_RESTART': '1', 05:55:26 INFO - 'PAGER': '/bin/cat', 05:55:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:26 INFO - 'PWD': '/builds/slave/test', 05:55:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:26 INFO - 'SHELL': '/bin/bash', 05:55:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVcwKUdqFG/Listeners', 05:55:26 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 05:55:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:26 INFO - 'USER': 'cltbld', 05:55:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:26 INFO - 'XPC_FLAGS': '0x0', 05:55:26 INFO - 'XPC_SERVICE_NAME': '0', 05:55:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:26 INFO - Downloading/unpacking flake8 05:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:55:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:55:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:55:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:55:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:55:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:55:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:55:30 INFO - no previously-included directories found matching 'docs/_build' 05:55:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:55:30 INFO - Running setup.py install for pep8 05:55:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:55:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:55:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:55:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:55:30 INFO - no previously-included directories found matching 'docs/_build' 05:55:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:55:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:55:30 INFO - Cleaning up... 05:55:30 INFO - Return code: 0 05:55:30 INFO - Running post-action listener: _resource_record_post_action 05:55:30 INFO - Running post-action listener: _start_resource_monitoring 05:55:30 INFO - Starting resource monitoring. 05:55:30 INFO - ##### 05:55:30 INFO - ##### Running install step. 05:55:30 INFO - ##### 05:55:30 INFO - Running pre-action listener: _resource_record_pre_action 05:55:30 INFO - Running main action method: install 05:55:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:55:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:55:30 INFO - Reading from file tmpfile_stdout 05:55:30 INFO - Detecting whether we're running mozinstall >=1.0... 05:55:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:55:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:55:31 INFO - Reading from file tmpfile_stdout 05:55:31 INFO - Output received: 05:55:31 INFO - Usage: mozinstall [options] installer 05:55:31 INFO - Options: 05:55:31 INFO - -h, --help show this help message and exit 05:55:31 INFO - -d DEST, --destination=DEST 05:55:31 INFO - Directory to install application into. [default: 05:55:31 INFO - "/builds/slave/test"] 05:55:31 INFO - --app=APP Application being installed. [default: firefox] 05:55:31 INFO - mkdir: /builds/slave/test/build/application 05:55:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:55:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 05:56:02 INFO - Reading from file tmpfile_stdout 05:56:02 INFO - Output received: 05:56:02 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:56:02 INFO - Running post-action listener: _resource_record_post_action 05:56:02 INFO - ##### 05:56:02 INFO - ##### Running run-tests step. 05:56:02 INFO - ##### 05:56:02 INFO - Running pre-action listener: _resource_record_pre_action 05:56:02 INFO - Running main action method: run_tests 05:56:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:56:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:56:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:56:02 INFO - 2016-05-09 05:56:02.503 screenresolution[1950:12580] starting screenresolution argv=screenresolution get 05:56:02 INFO - 2016-05-09 05:56:02.506 screenresolution[1950:12580] Display 0: 1600x1200x32@60 05:56:02 INFO - 2016-05-09 05:56:02.512 screenresolution[1951:12582] starting screenresolution argv=screenresolution list 05:56:02 INFO - Available Modes on Display 0 05:56:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:56:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:56:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:56:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:56:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:56:02 INFO - Intel Iris: 05:56:02 INFO - Chipset Model: Intel Iris 05:56:02 INFO - Type: GPU 05:56:02 INFO - Bus: Built-In 05:56:02 INFO - VRAM (Dynamic, Max): 1536 MB 05:56:02 INFO - Vendor: Intel (0x8086) 05:56:02 INFO - Device ID: 0x0a2e 05:56:02 INFO - Revision ID: 0x0009 05:56:02 INFO - Displays: 05:56:02 INFO - Display: 05:56:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:56:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:56:02 INFO - Display Serial Number: 1600x1200 60 05:56:02 INFO - Main Display: Yes 05:56:02 INFO - Mirror: Off 05:56:02 INFO - Online: Yes 05:56:02 INFO - Rotation: Supported 05:56:02 INFO - Return code: 0 05:56:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:56:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:56:02 INFO - Python 2.7.3 05:56:02 INFO - Return code: 0 05:56:02 INFO - grabbing minidump binary from tooltool 05:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:02 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 0x10ab92e00>, '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 0x10a9fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:56:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:56:02 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:56:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:56:02 INFO - Return code: 0 05:56:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:56:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:56:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:56:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:56:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:56:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:56:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:56:03 INFO - Return code: 0 05:56:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0174', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:56:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0174 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:56:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IdoKgSnjyz/Render', 05:56:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:03 INFO - 'HOME': '/Users/cltbld', 05:56:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:03 INFO - 'LOGNAME': 'cltbld', 05:56:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:56:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:03 INFO - 'MOZ_NO_REMOTE': '1', 05:56:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:56:03 INFO - 'NO_EM_RESTART': '1', 05:56:03 INFO - 'PAGER': '/bin/cat', 05:56:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:03 INFO - 'PWD': '/builds/slave/test', 05:56:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:56:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:03 INFO - 'SHELL': '/bin/bash', 05:56:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVcwKUdqFG/Listeners', 05:56:03 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 05:56:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:03 INFO - 'USER': 'cltbld', 05:56:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:03 INFO - 'XPC_FLAGS': '0x0', 05:56:03 INFO - 'XPC_SERVICE_NAME': '0', 05:56:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0174', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:56:04 INFO - mozversion application_buildid: 20160509005049 05:56:04 INFO - mozversion application_changeset: 307b5eca8726f6a72a1d69ba76630a6dbf402477 05:56:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:56:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:56:04 INFO - mozversion application_name: Firefox 05:56:04 INFO - mozversion application_remotingname: firefox-dev 05:56:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:56:04 INFO - mozversion application_vendor: Mozilla 05:56:04 INFO - mozversion application_version: 48.0a2 05:56:04 INFO - mozversion platform_buildid: 20160509005049 05:56:04 INFO - mozversion platform_changeset: 307b5eca8726f6a72a1d69ba76630a6dbf402477 05:56:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:56:04 INFO - mozversion platform_version: 48.0a2 05:56:04 INFO - using testdate: 1462798564 05:56:04 INFO - actual date: 1462798564 05:56:04 INFO - starting webserver on 'localhost:49511' 05:56:04 INFO - SUITE-START | Running 2 tests 05:56:04 INFO - TEST-START | dromaeo_css 05:56:04 INFO - operating with platform_type : mac_ 05:56:04 INFO - Initialising browser for dromaeo_css test... 05:56:04 INFO - TEST-INFO | started process 1969 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpj2ha6o/profile http://localhost:49511/getInfo.html) 05:56:06 INFO - PROCESS | 1969 | __metrics Screen width/height:1600/1200 05:56:06 INFO - PROCESS | 1969 | colorDepth:24 05:56:06 INFO - PROCESS | 1969 | Browser inner width/height: 1024/689 05:56:06 INFO - PROCESS | 1969 | __metrics 05:56:06 ERROR - PROCESS | 1969 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:56:07 INFO - TEST-INFO | 1969: exit 0 05:56:07 INFO - Browser initialized. 05:56:07 INFO - Running cycle 1/1 for dromaeo_css test... 05:56:07 INFO - TEST-INFO | started process 1972 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpj2ha6o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:56:19 INFO - PROCESS | 1972 | RSS: Main: 221110272 05:56:19 INFO - PROCESS | 1972 | 05:57:09 INFO - PROCESS | 1972 | 1462798629445 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:59:20 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49511/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49511/tests/dromaeo/cssquery-ext.html) 05:59:22 INFO - PROCESS | 1972 | RSS: Main: 230899712 05:59:22 INFO - PROCESS | 1972 | 06:02:24 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49511/tests/dromaeo/cssquery-ext.html (next: http://localhost:49511/tests/dromaeo/cssquery-jquery.html) 06:02:24 INFO - PROCESS | 1972 | RSS: Main: 242655232 06:02:24 INFO - PROCESS | 1972 | 06:05:26 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49511/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49511/tests/dromaeo/cssquery-mootools.html) 06:05:27 INFO - PROCESS | 1972 | RSS: Main: 244654080 06:05:27 INFO - PROCESS | 1972 | 06:08:28 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49511/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49511/tests/dromaeo/cssquery-prototype.html) 06:08:29 INFO - PROCESS | 1972 | RSS: Main: 246525952 06:08:29 INFO - PROCESS | 1972 | 06:11:32 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49511/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49511/tests/dromaeo/cssquery-yui.html) 06:11:32 INFO - PROCESS | 1972 | RSS: Main: 249376768 06:11:32 INFO - PROCESS | 1972 | 06:14:34 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49511/tests/dromaeo/cssquery-yui.html (next: http://localhost:49511/tests/dromaeo/cssquery-dojo.html) 06:14:35 INFO - PROCESS | 1972 | RSS: Main: 250462208 06:14:35 INFO - PROCESS | 1972 | 06:14:35 INFO - PROCESS | 1972 | __start_tp_report 06:14:35 INFO - PROCESS | 1972 | _x_x_mozilla_page_load 06:14:35 INFO - PROCESS | 1972 | _x_x_mozilla_page_load_details 06:14:35 INFO - PROCESS | 1972 | |i|pagename|runs| 06:14:35 INFO - PROCESS | 1972 | |0;dojo.html;3411.965880341196;3481.1297175706068;3499.160201551625;3504.877329293475;3517.982410087949;5772.884542309152;5789.681567513785;5806.825795226145;5842.386549412315;5843.970780146104;5822.330432000321;5849.912251316234;5897.557683173758;5904.763809447617;5927.673977931223;5245;5315.521603055724;5356.598255130881;5358.3569971603465;5392.245085688007;5231.267622532849;5330.600204984622;5344.305240318753;5379.569634429236;5384.973075134619;5317;5352.91970620441;5357.4106848246665;5370.060239458098;5378.273933019051;5314.202869569576;5348;5348.197770334442;5360;5371.355437347524;5182.067227898977;5267.446918073624;5271.499207575274;5291.920621190685;5355.491228333302;5711.029125048752;5745.1669507921115;5769.769209231626;5775.653460792366;5796.391378905197;6139.201903752528;6244.5316601254635;6267.905981410283;6296.1500197473215;6333.493320534346;6185.783497577563;6226.533010024221;6268.937310626881;6283.214598175228;6293.244810622755;5971.353038698756;5996.550258730569;6005.249343832021;6008.759649614054;6058.545609079337;6382.776602818879;6387.712552935129;6407;6411.2627047889155;6422.325655806167;6121.422488707842;6135.570510064342;6210.4100110489435;6266.561340706106;6286.339934306923;6244.968775156095;6268.09112678659;6287.2140982377205;6287.842803929939;6292.559520833498;6208.50331973441;6244.188255526752;6284.4344009039405;6289.213848268966;6297.055441683784;6241.7191226394925;6258.342873998165;6270.341614130451;6274.6548939807835;6301.4328710416285;4956.4547899731;4971.130052240844;4985.177445721415;5024.974875125674;5034.395872495251;4376.693631445768;4389.868303950887;4511.255642818898;4528.298113792367;4537.75042372666;5801.158831969426;5827.213326200908;5831.737571809279;5835.533157347402;5901.3508514063415;6258.405451482102;6267.2479302484;6271.717772700239;6276.560640755103;6280.9685951569945;5701.629394089454;5787.623804452697;5834.620749651259;5850.473457388855;5883.970580147157;5439.401665816757;5451.509364157245;5455.481729235717;5468.398476167618;5495.862603434866;6412.935870641327;6454.677266136769;6470.352964703586;6485.837854053686;6496.187976502937;6202.34875338101;6211.503079753699;6230.252369715663;6279;6291.1192433058495;6486.091947127312;6501.804945851632;6502.187226596676;6520.771772988112;6535.640539770358;5434.858679677154;6233.625982441068;6300.527460440394;6309.779157729641;6310.495160387066;6065.939340606537;6123.142760013513;6174.043023331391;6214.937850621617;6231.158793562811;6167.259928808572;6192.071189321638;6222.564420490522;6239.313675495601;6241.812745617639;6089.421504957022;6168.1672974149715;6263.68681565929;6275.152854364603;6290.21372328459;5132.2814805926455;5624.634398764068;5977.103434484862;6037.18498002764;6166.260048794174;6194.566380353332;6201.565890387629;6228.128062071404;6239.06414037898;6243.469305109116;5969.224000880032;6098.176746139215;6139.171211886338;6151.138840562236;6181.103739957591 06:14:35 INFO - PROCESS | 1972 | |1;ext.html;484.3461327208269;511.9683837068308;525.1597444089456;536.0725944116678;557.8410153106365;35237;40373.192536149334;40380.394294085614;40422.797886010565;40427.59572404279;41698;42144.789276053576;42234.944126396855;42286.577134228726;42411.78794106033;9393.624255029808;9566.712998610054;9615.56729947154;9621.711348659517;9650.276229282797;9506.667266645634;9739.561719722595;9755.317127801056;9768.413895166277;9778.315517913748;9717.076877696607;9747.805043899118;9758.756031099243;9776;9793.26550508711;9686.75783105421;9756.268279879037;9761.365511241747;9791.363561368487;9791.902080979171;8372.748817535483;9672.90327096727;9770;9787.657431989846;9792.118709316124;40612.593874061175;40742.79628601838;41187.58812411867;41224.79387603073;41236.38145427821;3232.531282963957;3309.7352211822995;3323.0363194673514;3460.325236578868;3666.6699997000396;29079.273018174503;29276.853615731998;29285.70714292851;29986.700132998827;30034.399312013855;2795.8741856616502;2801.705820888798;2822.5483922572494;2868.4836729388703;2869.1105757215123;467376;470828;474788.62605687097;475809;476911;36763;66004.33995660079;66382.33617663858;66519;66679.66640667159;37688;38048.809755951595;38159.85520434391;38197.42703859444;38221.42667859984;16000.919995399949;16045.59886002836;16314.918425407797;16338.346466141458;16356;13705;13819.792703109462;13821.585352439442;13822.654433639038;13824.239666818421;8319.500829950222;8364.749057528285;8447.577621118919;8479.321654267644;8494.447860889022;3010.8795648174264;3432.2449061206494;3489.74911182286;3499.650034996531;3600.8657272958935;854.679495189304;886.9135309246251;961.403929563661;978.161057212436;1028.6502589119734;25459.61810572843;26644.934202632066;26680.466390672078;28716.71283287182;28880.711192888222;1012.1750773119884;1066.3069005146717;1087.7335052912088;1095.7644106517137;1149.3793351590216;14422.711545769236;14735.336909839083;14744.63138421548;14992.250387480583;15046.32291546883;606738;612883;616777;620989;623090;63592;64308.035379469344;65151.348486515475;65234;65248.36879078061;1471.7350876842056;1476.4168153579492;1507.5175943697911;1513.9243037848064;1529.3576697786957;24068.19687813011;28303.433931320847;28601.42797144004;28745;28864.42271154523;54627;55319.72340138273;55601.721991389786;56056.15915761348;56381;545940;559810;559822;565405;565772;27675.169744907686;28153.859230703714;28583.857080714464;44201.33697994468;45391.31913021241;44937.101257974005;45171.774141129084;45703.77148114238;45837.08325833531;46067.84830379267;36923.261534768615;37826.24347513089;40127.19745605013;40183;41293.96765080897;30994;33088;35743.64256357507;35828.462573060904;36109.81945090258;36402;36505.45241821428;38596.421053683654;39985;39997.80006599807;31430.528542072345;32759.344813104082;32791.18022049468;32914;32998.67001329956;19326.806731932502;20072.397828065183;23550.175743848882;24220.394490137183;24700.752992469847 06:14:35 INFO - PROCESS | 1972 | |2;jquery.html;404.06660613981694;405.38381659876967;407.7553467919248;408.97750623715683;417.111552393402;14781.852181478182;20099.899500502495;20705;20855.27006554771;20900.790992090075;18684.439466816017;20129.29547465839;20372.89813550934;20500.69248961267;21252.89373553134;4853.563179313852;5787.594868359217;6083.239595050619;6087.178230938833;6090.482309003718;5971.820845374624;6014.338422773491;6028.336882942873;6105.664188469633;6106.603070800406;6076.2708474983065;6086.878262434749;6090.5736598438125;7175.138983322008;7620.809479763023;7427.517211381297;7517.736879209201;7535;7659.463837531375;7934.603269836485;7708.65311060998;7737.187595302525;7744.2642948919765;7749.496282741604;7771.261730135628;29329.853350733323;30366.69633303661;30399.848000760077;30716.692833071607;31319;2275.9317220483413;2442.987785061081;2529.607910773833;2545.0583284184786;2557.9872100639564;15795.921020394939;15820.288087036111;15848.841511584968;16100.516984490485;16554.751678724828;1893.848492120627;1928.4311128217068;1930.691089425685;1933.9806601933942;1979.7228388025687;117436;118165;126489;134197.32901335528;135000.9749853743;19786.70319945202;20523.076461559267;22172.223972160897;22425.10299588031;22534.21130260433;32403.35193296121;34004.82997584996;35817;36019.09952251162;36892.63107368946;11366.147538934612;11655.76688466243;11691.76616467666;11721.062315014779;11856.70358241051;9543.188828949616;9549;9565.473898935625;9658.985806490358;9672.08115229052;9187.632494700138;10339.327943683787;10455;10536.894631053745;10633.627823026312;3997.6801855851463;4079.755214687128;4117.341225403921;4194.8112334945;4307.5261721210645;797.8643630582815;808.1433680298902;863.0980625246632;894.2577660541734;923.459776031016;17440.912795436194;19074.04629768506;19830.603387932162;19920.900395498218;20022.299219527245;896.9237614802685;915.2357781252729;919.8068405634758;980.3333733061587;985.7387792235063;10092.021941777539;10408.843867341995;10413.42726150069;10439.37363758177;10578.206634502443;616214;623120;635533;642911.7854410792;643862;113060;115456;116762;118032.40983795025;119808;977.8533220017055;985.3595163144014;990.3166814897697;997.5411310796969;1023.0741179232806;17255.309787608858;17394.5651358712;17634.470965871045;18194.90902545479;19380.90309548443;50599.74700126476;52613.947721046134;53251.467485324654;54540.727296363264;55736.44263557313;67860;70238;72303;72905.6354718223;74681;8003.599820009093;8147.79630509242;8156.898818659405;8284.751457456287;8456.957715211385;11439.942800285946;12770.61688149357;12821.974242060427;13024.218546887218;13055.673608159872;7711.843223516517;10106.090451859369;10513.78972420532;10672.679819605424;10733.195010374096;5089.8727531812;8613.526256055977;9797.922228554995;10818.350898946088;10940.288881222696;10792.08267297257;10827;10849.349039057683;11194.888051119384;11271.605493807678;8658.696945606873;11673.241239319417;12008.459619317326;12260.75478490443;12477.251364918135;9575.09036641518;10860.565577377132;11226.382548959558;11337.546498139982;11398.829017564578 06:14:35 INFO - PROCESS | 1972 | |3;mootools.html;1477.1580199286404;1578.889477736558;1777.5289548269711;1864.8601354898378;1909.8090190980913;7967.20327967203;8607.354448416363;8789.208971192591;9021.007689154196;9026.4132831366;6439.581427207228;8697.608607612658;8748.737537873858;8815.603297851596;9150.90849091509;2007.6988451732284;2389.665446837444;2453.472503411766;2484.1181380609896;2488.129154795825;2462.729099799021;2476.0590975429313;2514.585093459577;2515.9874200629065;2516.471540976397;2465.568525508038;2490.8256422050463;2503.436726736488;2522.369407648088;2525.154073385418;2471.938201544958;2512.196097248881;2522.155078048856;2522.5081109183716;2524.798016158721;2485.751424857518;2490.1284550407213;2512.4095837478176;2515.8238923275376;2522.735112813165;8839.77900552485;8899.73300800977;8911.821763564758;8925.776855578597;8933.41932774374;5457.99027179973;5532.170174473821;5544.667319960775;5566.832995010116;5624.3250809902665;3540.7344449166417;3541.4687796830426;3591.317649646559;3695.9630403696156;3715.6655900969045;1055.0508702931431;1070.925035247533;1071.8582522148058;1073.7047311989188;1086.9456527173609;11937.940310298392;12152.270863748203;12154.149209555335;12221.144519883612;12242.510299588095;4894.779734911866;4919.975400122977;4956.3804524434445;5006.299118123466;5020.347354843847;10235.488225588691;10307.896921030693;10311.896881031244;10313.226508011929;10328.431936243429;4643.442786865598;5509.146082357241;5572.944270557243;5584;5616.887662246814;4465.620422264144;4882.340883980688;5001.724905130252;5037.345145131109;5059.97470012655;4152.8131234094535;4157.91684166315;4212.726172798758;4241.618254357123;4253.48958125027;1173.3077484284313;1369.6096612465399;1384.210999730144;1396.441423430636;1401.4114072089747;834.4479268560473;905.3210092430677;906.9501177435304;919.2462181011506;922.8431166701542;5719.084946408555;5801.274840644919;5821.7380217890295;5823.767049318065;5910.320313163955;431.20873197681976;443.60740744441404;455.3647661512238;472.3269341188792;477.238804107455;3268.297316077055;3729.6083911188935;3770.321342158438;3779.7354185207323;3782.6028267031566;10524.684259472227;10810.459477026117;10900.182486313559;10936.945315273373;10960.56157753681;10526.842097368546;10650.89349106499;10737.355758654505;10810.513526891309;10858.34849909008;8362.8327433451;8369.62336694838;8461.661533538589;8462.02686691026;8506.064332923374;5397.244385786072;5589.524890384363;5607.355154157324;5681.630694004876;5712.143178523254;9310;9583.712488625353;9598.568064437257;9603.519824008632;9731.17285030752;9415.435073895589;9745.561449734638;9912.00879912003;9935.105840474393;10035.498225088862;6835.487338449537;7311.488195826241;7491.250874912465;7517.43619228571;7611.4291428144215;8279.13069127757;8289.04675962272;8312.21034001769;8326.542040187847;8348.290395316466;7006.789796306119;7409.66656500448;7422.888656670047;7457.850842983002;7525.247475252431;6193.597416167935;7334.633268336668;7352;7361.595112268662;7425.554466732014;6899.48253880971;6917.066196063467;6937.82655433598;6938.410235130071;6951.582905025714;4736.384270044872;4763.999560092419;4805.5915247204375;4851.369321988119;4862.513748625109;4846.539578740014;4882.951170488392;4912.680675756064;4999.350084488993;5047.823326183566 06:14:35 INFO - PROCESS | 1972 | |4;prototype.html;320.58324178567864;321.080105497749;327.217949101647;328.2515863830466;332.0271604199697;6784.966075169617;6785.355391237837;6792.796216113518;6813.182418109822;6821.386075253226;6791.117154769873;6817.761378351759;6825.931740682592;6828.2488926218075;6859;4846.709197448146;4888.828890988798;5028.371453568304;5031.597472202215;5033.521815427547;3843.7117216208894;4413.161499315136;4969.602431805447;5011.32347133136;5032.144535428967;4053.918921621566;4959;5026.346574945252;5028.209343814182;5035.974820125894;4519.276915693474;5012.3483947086825;5029.497050294978;5048.848534543969;5050.065737838511;3535.7171426285836;4004.7196696231276;4323.178596066738;4342.5874541918465;4354.477462704495;5860.853478663025;6476.996065609838;6563.081168636395;6653.53425260232;6750.594964302109;4821.662483626147;5930.584859059867;5979.671118088503;5996.550258730569;6005.939940600581;6016.729247183887;7097.609631470267;7280.708771649128;7304.744333948286;7305.963470182668;4820.734859582722;4821.059893320804;4825.662203645747;4827.58965487934;4838.975805120952;7698;7705.267999540035;7720.498167619143;7727.304542591138;7737.9226207737765;3913.021744563859;4190.664746820308;4740.19416699155;4789.85043589543;4798.160321943716;3963.6432721055244;4876.853694389174;4887.535684110004;4887.73117478535;4893.703168660308;5853.239078919798;5863.794767183128;5877.353491115974;5882.411758824169;5892.086726557391;7356.264373562601;7379.262073792578;7394.26057394267;7395.445341599401;7419.332260096509;1396.3506969259342;1667.1997441228277;1683.7811084559173;1695.3049249807766;1698.431025606411;1060.6552870317023;1081.4106312059946;1103.2828661370174;1140.5152810055858;1146.2893006335958;6235.2564871457325;6641.634710090895;6712.96643516789;6742.83142921421;6762.323767623198;6153;7333.266673332624;7590.3927685785;7594.848103037908;7628.466007379541;6641.535092543476;6751.4261287790105;6825.69284382204;6845.281245469254;6849.109615750021;3582.659647333499;3821.7133714971487;4046.968023154077;4057.107436363995;4083.4283200351974;8060.798480038046;8075.313598344089;8096.554689492016;8105.878411823827;8116.959415202886;7513.985611942428;7530.28462296081;7925.0886148093705;7954.164812694699;7959;8026.558539280396;8084.959575202204;8091.231333023354;8093.959530202312;8101.837963240702;7662.578558179353;7683.039620047494;7711.730089446843;7714.305712485679;7714.56701767708;7512.774616761506;7551;7554.357879580078;7556.131044929683;7571.621418929141;7780.2219778021745;8033.8393232136195;8089.676412943416;8104.189581041848;8106.310963568163;4948;4994;4999.500049994971;5002;5028.547430731252;7639.732609358646;7646.770596882103;7666.425018123551;7675.808104797425;7693.769186924401;6745.325467453215;7371.299726525972;7383.446241532014;7393.482456228013;7419.8145046374275;5084.491550845034;6470.579412338183;7349.265073492608;7397.3342399182175;7420.703171873065;5398.757055932414;7147.7491438999;7190.7483238086415;7226.313500217471;7229.421646268391;6070.816190842744;7378.741744038933;7379.446541509301;7395.186529481645;7418.443616728659;6982.511224214256;7215.819604509929;7240.203577606354;7244.384227340524;7253.347198752138 06:14:35 INFO - PROCESS | 1972 | |5;yui.html;698.6402002968472;709.2481969112738;737.7749786315178;743.5538676793923;757.1293013035009;15070.39718411265;15083.849161508395;15286.923565382185;15351.53945381639;17044.318227270924;15555.84444155558;16070.5982350441;16109.677806443864;16193.433229836928;16301.755473667905;738.8780851159552;906.5421961909243;917.1516347378648;926.106307413346;926.9165775080241;931.3154831199063;933.439936038375;935.1817159985013;936.1948724097305;937.3298091864316;935.508857849631;967.4823969176492;973;973.5618971462835;974.770928831724;959.3955807841015;963.8939716631165;968.9321747477679;969.3263182088451;970.0105891990202;694.90966174397;720.3912693773752;723.0166972916829;726.6366816591704;728.4864170759655;15124.848751512454;15240.466583669519;15418.614534636723;15484.148371839545;15504.30230639624;12386.504539818396;12406.379681016004;12451.377431128498;12512.124151309412;12552.43514041861;8704.521251331174;10098.747531311703;10273.434961077137;10397.948010259975;10487.895121048768;3270.591176102987;3308.0902751743224;3309.7517686173633;3359.1266270769534;3386.5936087669393;77538.22461775366;78256.60871695718;78439;80070.59964700139;81412.59293703553;9438.811223775487;9455.479948602755;9512.857307140399;9542.475163865915;9678.70963871085;20181.293654722012;20763.68854467184;20902.686459703116;20916.895415522824;20966;7289.198188199294;7335.559866408033;7423.9257607424315;7498.5125966813075;7500.474966752275;3473.6179020307745;4096.91806163881;4119.938200926988;4144.979275103666;4163.479565054368;3592.7125829933543;3665.1020499977676;3672.779633222015;3673.9265214695556;3676.852925882935;503.7808553279335;588.8380695308739;599.7091410665823;652.9389741669787;668.3583759590847;542.7421974562052;557.6932687022203;561.5142901390268;567.3617180671745;618.3445547719432;6729.831754206184;7773.378129749606;7855.803604909809;7915.445918785745;7952.801179970431;580.613891761982;589.7169358707845;600.1388008208235;607.2561112637072;609.2049874913192;3657.6159503252306;3719.8512059517857;3750.081230098643;3764.4917936078828;3802.8098595070137;93020;93895.65260868396;93943.53028234815;94021;94351;74975;75377.62311188519;77119.61440192763;77780.83328750073;80114;69388;69684;70354.64822675854;70685.64657176682;71557.2844271561;7029.156501219682;7383.778486645409;7414.147373052168;7443.776686699408;7771.922280777346;45601.77199114116;47210.76394618005;47279.29081063856;47324.526754733386;47553;61278.38721612727;65535.01697474637;66513.66972660423;67166;67185.66407167933;9983.60065597397;10077.798444031225;10139.391636501834;10149.188064954638;10173.898261017295;10701.732456688645;10796.298240614335;10920.563177472812;11002.614908478165;11585.420728963687;9776.804463910825;9951.452670103214;10046.748831279276;10196.235282353704;10451.059404653617;9665.081817227352;9692.273079519206;9746.805063898824;9819.410835349901;10216.948915255674;9974.850377244202;10217.642382516577;10803.405812680063;11062.39156846349;11133.944330278297;9558.761030974281;9588.712338629852;9691.2731545133;9730.41617502952;9977.20182385393;9462.668806591737;9464.716058518256;9529.57116929726;9556.90443095588;9565.234781217347 06:14:35 INFO - PROCESS | 1972 | __end_tp_report 06:14:35 INFO - PROCESS | 1972 | __start_cc_report 06:14:35 INFO - PROCESS | 1972 | _x_x_mozilla_cycle_collect,427 06:14:35 INFO - PROCESS | 1972 | __end_cc_report 06:14:35 INFO - PROCESS | 1972 | __startTimestamp1462799675198__endTimestamp 06:14:35 INFO - PROCESS | 1972 | 06:14:35 INFO - PROCESS | 1972 | ------- Summary: start ------- 06:14:35 INFO - PROCESS | 1972 | Number of tests: 6 06:14:35 INFO - PROCESS | 1972 | 06:14:35 INFO - PROCESS | 1972 | [#0] dojo.html Cycles:165 Average:5830.34 Median:6089.42 stddev:633.56 (10.4%) stddev-sans-first:606.44 06:14:35 INFO - PROCESS | 1972 | Values: 3412.0 3481.1 3499.2 3504.9 3518.0 5772.9 5789.7 5806.8 5842.4 5844.0 5822.3 5849.9 5897.6 5904.8 5927.7 5245.0 5315.5 5356.6 5358.4 5392.2 5231.3 5330.6 5344.3 5379.6 5385.0 5317.0 5352.9 5357.4 5370.1 5378.3 5314.2 5348.0 5348.2 5360.0 5371.4 5182.1 5267.4 5271.5 5291.9 5355.5 5711.0 5745.2 5769.8 5775.7 5796.4 6139.2 6244.5 6267.9 6296.2 6333.5 6185.8 6226.5 6268.9 6283.2 6293.2 5971.4 5996.6 6005.2 6008.8 6058.5 6382.8 6387.7 6407.0 6411.3 6422.3 6121.4 6135.6 6210.4 6266.6 6286.3 6245.0 6268.1 6287.2 6287.8 6292.6 6208.5 6244.2 6284.4 6289.2 6297.1 6241.7 6258.3 6270.3 6274.7 6301.4 4956.5 4971.1 4985.2 5025.0 5034.4 4376.7 4389.9 4511.3 4528.3 4537.8 5801.2 5827.2 5831.7 5835.5 5901.4 6258.4 6267.2 6271.7 6276.6 6281.0 5701.6 5787.6 5834.6 5850.5 5884.0 5439.4 5451.5 5455.5 5468.4 5495.9 6412.9 6454.7 6470.4 6485.8 6496.2 6202.3 6211.5 6230.3 6279.0 6291.1 6486.1 6501.8 6502.2 6520.8 6535.6 5434.9 6233.6 6300.5 6309.8 6310.5 6065.9 6123.1 6174.0 6214.9 6231.2 6167.3 6192.1 6222.6 6239.3 6241.8 6089.4 6168.2 6263.7 6275.2 6290.2 5132.3 5624.6 5977.1 6037.2 6166.3 6194.6 6201.6 6228.1 6239.1 6243.5 5969.2 6098.2 6139.2 6151.1 6181.1 06:14:35 INFO - PROCESS | 1972 | 06:14:35 INFO - PROCESS | 1972 | [#1] ext.html Cycles:180 Average:67690.83 Median:40376.79 stddev:147771.53 (366.0%) stddev-sans-first:148099.92 06:14:35 INFO - PROCESS | 1972 | Values: 484.3 512.0 525.2 536.1 557.8 35237.0 40373.2 40380.4 40422.8 40427.6 41698.0 42144.8 42234.9 42286.6 42411.8 9393.6 9566.7 9615.6 9621.7 9650.3 9506.7 9739.6 9755.3 9768.4 9778.3 9717.1 9747.8 9758.8 9776.0 9793.3 9686.8 9756.3 9761.4 9791.4 9791.9 8372.7 9672.9 9770.0 9787.7 9792.1 40612.6 40742.8 41187.6 41224.8 41236.4 3232.5 3309.7 3323.0 3460.3 3666.7 29079.3 29276.9 29285.7 29986.7 30034.4 2795.9 2801.7 2822.5 2868.5 2869.1 467376.0 470828.0 474788.6 475809.0 476911.0 36763.0 66004.3 66382.3 66519.0 66679.7 37688.0 38048.8 38159.9 38197.4 38221.4 16000.9 16045.6 16314.9 16338.3 16356.0 13705.0 13819.8 13821.6 13822.7 13824.2 8319.5 8364.7 8447.6 8479.3 8494.4 3010.9 3432.2 3489.7 3499.7 3600.9 854.7 886.9 961.4 978.2 1028.7 25459.6 26644.9 26680.5 28716.7 28880.7 1012.2 1066.3 1087.7 1095.8 1149.4 14422.7 14735.3 14744.6 14992.3 15046.3 606738.0 612883.0 616777.0 620989.0 623090.0 63592.0 64308.0 65151.3 65234.0 65248.4 1471.7 1476.4 1507.5 1513.9 1529.4 24068.2 28303.4 28601.4 28745.0 28864.4 54627.0 55319.7 55601.7 56056.2 56381.0 545940.0 559810.0 559822.0 565405.0 565772.0 27675.2 28153.9 28583.9 44201.3 45391.3 44937.1 45171.8 45703.8 45837.1 46067.8 36923.3 37826.2 40127.2 40183.0 41294.0 30994.0 33088.0 35743.6 35828.5 36109.8 36402.0 36505.5 38596.4 39985.0 39997.8 31430.5 32759.3 32791.2 32914.0 32998.7 19326.8 20072.4 23550.2 24220.4 24700.8 06:14:35 INFO - PROCESS | 1972 | 06:14:35 INFO - PROCESS | 1972 | [#2] jquery.html Cycles:180 Average:37236.99 Median:30383.27 stddev:104838.10 (345.1%) stddev-sans-first:105095.72 06:14:35 INFO - PROCESS | 1972 | Values: 404.1 405.4 407.8 409.0 417.1 14781.9 20099.9 20705.0 20855.3 20900.8 18684.4 20129.3 20372.9 20500.7 21252.9 4853.6 5787.6 6083.2 6087.2 6090.5 5971.8 6014.3 6028.3 6105.7 6106.6 6076.3 6086.9 6090.6 7175.1 7620.8 7427.5 7517.7 7535.0 7659.5 7934.6 7708.7 7737.2 7744.3 7749.5 7771.3 29329.9 30366.7 30399.8 30716.7 31319.0 2275.9 2443.0 2529.6 2545.1 2558.0 15795.9 15820.3 15848.8 16100.5 16554.8 1893.8 1928.4 1930.7 1934.0 1979.7 117436.0 118165.0 126489.0 134197.3 135001.0 19786.7 20523.1 22172.2 22425.1 22534.2 32403.4 34004.8 35817.0 36019.1 36892.6 11366.1 11655.8 11691.8 11721.1 11856.7 9543.2 9549.0 9565.5 9659.0 9672.1 9187.6 10339.3 10455.0 10536.9 10633.6 3997.7 4079.8 4117.3 4194.8 4307.5 797.9 808.1 863.1 894.3 923.5 17440.9 19074.0 19830.6 19920.9 20022.3 896.9 915.2 919.8 980.3 985.7 10092.0 10408.8 10413.4 10439.4 10578.2 616214.0 623120.0 635533.0 642911.8 643862.0 113060.0 115456.0 116762.0 118032.4 119808.0 977.9 985.4 990.3 997.5 1023.1 17255.3 17394.6 17634.5 18194.9 19380.9 50599.7 52613.9 53251.5 54540.7 55736.4 67860.0 70238.0 72303.0 72905.6 74681.0 8003.6 8147.8 8156.9 8284.8 8457.0 11439.9 12770.6 12822.0 13024.2 13055.7 7711.8 10106.1 10513.8 10672.7 10733.2 5089.9 8613.5 9797.9 10818.4 10940.3 10792.1 10827.0 10849.3 11194.9 11271.6 8658.7 11673.2 12008.5 12260.8 12477.3 9575.1 10860.6 11226.4 11337.5 11398.8 06:14:35 INFO - PROCESS | 1972 | 06:14:35 INFO - PROCESS | 1972 | [#3] mootools.html Cycles:180 Average:5725.82 Median:4888.87 stddev:3213.30 (65.7%) stddev-sans-first:3206.45 06:14:35 INFO - PROCESS | 1972 | Values: 1477.2 1578.9 1777.5 1864.9 1909.8 7967.2 8607.4 8789.2 9021.0 9026.4 6439.6 8697.6 8748.7 8815.6 9150.9 2007.7 2389.7 2453.5 2484.1 2488.1 2462.7 2476.1 2514.6 2516.0 2516.5 2465.6 2490.8 2503.4 2522.4 2525.2 2471.9 2512.2 2522.2 2522.5 2524.8 2485.8 2490.1 2512.4 2515.8 2522.7 8839.8 8899.7 8911.8 8925.8 8933.4 5458.0 5532.2 5544.7 5566.8 5624.3 3540.7 3541.5 3591.3 3696.0 3715.7 1055.1 1070.9 1071.9 1073.7 1086.9 11937.9 12152.3 12154.1 12221.1 12242.5 4894.8 4920.0 4956.4 5006.3 5020.3 10235.5 10307.9 10311.9 10313.2 10328.4 4643.4 5509.1 5572.9 5584.0 5616.9 4465.6 4882.3 5001.7 5037.3 5060.0 4152.8 4157.9 4212.7 4241.6 4253.5 1173.3 1369.6 1384.2 1396.4 1401.4 834.4 905.3 907.0 919.2 922.8 5719.1 5801.3 5821.7 5823.8 5910.3 431.2 443.6 455.4 472.3 477.2 3268.3 3729.6 3770.3 3779.7 3782.6 10524.7 10810.5 10900.2 10936.9 10960.6 10526.8 10650.9 10737.4 10810.5 10858.3 8362.8 8369.6 8461.7 8462.0 8506.1 5397.2 5589.5 5607.4 5681.6 5712.1 9310.0 9583.7 9598.6 9603.5 9731.2 9415.4 9745.6 9912.0 9935.1 10035.5 6835.5 7311.5 7491.3 7517.4 7611.4 8279.1 8289.0 8312.2 8326.5 8348.3 7006.8 7409.7 7422.9 7457.9 7525.2 6193.6 7334.6 7352.0 7361.6 7425.6 6899.5 6917.1 6937.8 6938.4 6951.6 4736.4 4764.0 4805.6 4851.4 4862.5 4846.5 4883.0 4912.7 4999.4 5047.8 06:14:35 INFO - PROCESS | 1972 | 06:14:35 INFO - PROCESS | 1972 | [#4] prototype.html Cycles:180 Average:5944.19 Median:6747.96 stddev:1962.94 (29.1%) stddev-sans-first:1922.53 06:14:35 INFO - PROCESS | 1972 | Values: 320.6 321.1 327.2 328.3 332.0 6785.0 6785.4 6792.8 6813.2 6821.4 6791.1 6817.8 6825.9 6828.2 6859.0 4846.7 4888.8 5028.4 5031.6 5033.5 3843.7 4413.2 4969.6 5011.3 5032.1 4053.9 4959.0 5026.3 5028.2 5036.0 4519.3 5012.3 5029.5 5048.8 5050.1 3535.7 4004.7 4323.2 4342.6 4354.5 5860.9 6477.0 6563.1 6653.5 6750.6 4821.7 5930.6 5979.7 5996.6 6005.9 6016.7 7097.6 7280.7 7304.7 7306.0 4820.7 4821.1 4825.7 4827.6 4839.0 7698.0 7705.3 7720.5 7727.3 7737.9 3913.0 4190.7 4740.2 4789.9 4798.2 3963.6 4876.9 4887.5 4887.7 4893.7 5853.2 5863.8 5877.4 5882.4 5892.1 7356.3 7379.3 7394.3 7395.4 7419.3 1396.4 1667.2 1683.8 1695.3 1698.4 1060.7 1081.4 1103.3 1140.5 1146.3 6235.3 6641.6 6713.0 6742.8 6762.3 6153.0 7333.3 7590.4 7594.8 7628.5 6641.5 6751.4 6825.7 6845.3 6849.1 3582.7 3821.7 4047.0 4057.1 4083.4 8060.8 8075.3 8096.6 8105.9 8117.0 7514.0 7530.3 7925.1 7954.2 7959.0 8026.6 8085.0 8091.2 8094.0 8101.8 7662.6 7683.0 7711.7 7714.3 7714.6 7512.8 7551.0 7554.4 7556.1 7571.6 7780.2 8033.8 8089.7 8104.2 8106.3 4948.0 4994.0 4999.5 5002.0 5028.5 7639.7 7646.8 7666.4 7675.8 7693.8 6745.3 7371.3 7383.4 7393.5 7419.8 5084.5 6470.6 7349.3 7397.3 7420.7 5398.8 7147.7 7190.7 7226.3 7229.4 6070.8 7378.7 7379.4 7395.2 7418.4 6982.5 7215.8 7240.2 7244.4 7253.3 06:14:35 INFO - PROCESS | 1972 | 06:14:35 INFO - PROCESS | 1972 | [#5] yui.html Cycles:180 Average:17990.42 Median:36957.75 stddev:25502.12 (69.0%) stddev-sans-first:25540.61 06:14:35 INFO - PROCESS | 1972 | Values: 698.6 709.2 737.8 743.6 757.1 15070.4 15083.8 15286.9 15351.5 17044.3 15555.8 16070.6 16109.7 16193.4 16301.8 738.9 906.5 917.2 926.1 926.9 931.3 933.4 935.2 936.2 937.3 935.5 967.5 973.0 973.6 974.8 959.4 963.9 968.9 969.3 970.0 694.9 720.4 723.0 726.6 728.5 15124.8 15240.5 15418.6 15484.1 15504.3 12386.5 12406.4 12451.4 12512.1 12552.4 8704.5 10098.7 10273.4 10397.9 10487.9 3270.6 3308.1 3309.8 3359.1 3386.6 77538.2 78256.6 78439.0 80070.6 81412.6 9438.8 9455.5 9512.9 9542.5 9678.7 20181.3 20763.7 20902.7 20916.9 20966.0 7289.2 7335.6 7423.9 7498.5 7500.5 3473.6 4096.9 4119.9 4145.0 4163.5 3592.7 3665.1 3672.8 3673.9 3676.9 503.8 588.8 599.7 652.9 668.4 542.7 557.7 561.5 567.4 618.3 6729.8 7773.4 7855.8 7915.4 7952.8 580.6 589.7 600.1 607.3 609.2 3657.6 3719.9 3750.1 3764.5 3802.8 93020.0 93895.7 93943.5 94021.0 94351.0 74975.0 75377.6 77119.6 77780.8 80114.0 69388.0 69684.0 70354.6 70685.6 71557.3 7029.2 7383.8 7414.1 7443.8 7771.9 45601.8 47210.8 47279.3 47324.5 47553.0 61278.4 65535.0 66513.7 67166.0 67185.7 9983.6 10077.8 10139.4 10149.2 10173.9 10701.7 10796.3 10920.6 11002.6 11585.4 9776.8 9951.5 10046.7 10196.2 10451.1 9665.1 9692.3 9746.8 9819.4 10216.9 9974.9 10217.6 10803.4 11062.4 11133.9 9558.8 9588.7 9691.3 9730.4 9977.2 9462.7 9464.7 9529.6 9556.9 9565.2 06:14:35 INFO - PROCESS | 1972 | -------- Summary: end -------- 06:14:35 INFO - PROCESS | 1972 | 06:14:35 INFO - TEST-INFO | 1972: exit 0 06:14:35 INFO - TEST-OK | dromaeo_css | took 1111570ms 06:14:35 INFO - TEST-START | kraken 06:14:35 INFO - operating with platform_type : mac_ 06:14:35 INFO - Initialising browser for kraken test... 06:14:35 INFO - TEST-INFO | started process 1977 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpcCk3nc/profile http://localhost:49511/getInfo.html) 06:14:38 INFO - PROCESS | 1977 | __metrics Screen width/height:1600/1200 06:14:38 INFO - PROCESS | 1977 | colorDepth:24 06:14:38 INFO - PROCESS | 1977 | Browser inner width/height: 1024/689 06:14:38 INFO - PROCESS | 1977 | __metrics 06:14:38 ERROR - PROCESS | 1977 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 06:14:39 INFO - TEST-INFO | 1977: exit 0 06:14:39 INFO - Browser initialized. 06:14:39 INFO - Running cycle 1/1 for kraken test... 06:14:39 INFO - TEST-INFO | started process 1980 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpcCk3nc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:14:51 INFO - PROCESS | 1980 | RSS: Main: 220274688 06:14:51 INFO - PROCESS | 1980 | 06:14:53 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49511/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49511/tests/kraken/driver.html?testName=audio-beat-detection) 06:14:54 INFO - PROCESS | 1980 | RSS: Main: 232611840 06:14:54 INFO - PROCESS | 1980 | 06:14:57 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49511/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49511/tests/kraken/driver.html?testName=audio-dft) 06:14:57 INFO - PROCESS | 1980 | RSS: Main: 241905664 06:14:57 INFO - PROCESS | 1980 | 06:15:01 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49511/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49511/tests/kraken/driver.html?testName=audio-fft) 06:15:01 INFO - PROCESS | 1980 | RSS: Main: 246280192 06:15:01 INFO - PROCESS | 1980 | 06:15:03 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49511/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49511/tests/kraken/driver.html?testName=audio-oscillator) 06:15:04 INFO - PROCESS | 1980 | RSS: Main: 245870592 06:15:04 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49511/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49511/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:15:07 INFO - PROCESS | 1980 | RSS: Main: 246124544 06:15:07 INFO - PROCESS | 1980 | 06:15:10 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49511/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49511/tests/kraken/driver.html?testName=imaging-darkroom) 06:15:10 INFO - PROCESS | 1980 | RSS: Main: 247201792 06:15:10 INFO - PROCESS | 1980 | 06:15:13 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49511/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49511/tests/kraken/driver.html?testName=imaging-desaturate) 06:15:14 INFO - PROCESS | 1980 | RSS: Main: 247398400 06:15:14 INFO - PROCESS | 1980 | 06:15:17 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49511/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49511/tests/kraken/driver.html?testName=json-parse-financial) 06:15:17 INFO - PROCESS | 1980 | RSS: Main: 247521280 06:15:17 INFO - PROCESS | 1980 | 06:15:19 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49511/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49511/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:15:20 INFO - PROCESS | 1980 | RSS: Main: 247304192 06:15:20 INFO - PROCESS | 1980 | 06:15:22 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49511/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49511/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:15:22 INFO - PROCESS | 1980 | RSS: Main: 247259136 06:15:22 INFO - PROCESS | 1980 | 06:15:24 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49511/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49511/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:15:25 INFO - PROCESS | 1980 | RSS: Main: 247558144 06:15:25 INFO - PROCESS | 1980 | 06:15:28 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49511/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49511/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:15:29 INFO - PROCESS | 1980 | RSS: Main: 247517184 06:15:29 INFO - PROCESS | 1980 | 06:15:31 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49511/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49511/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:15:32 INFO - PROCESS | 1980 | RSS: Main: 248135680 06:15:32 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49511/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49511/tests/kraken/driver.html?testName=ai-astar) 06:15:34 INFO - PROCESS | 1980 | RSS: Main: 249155584 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | __start_tp_report 06:15:34 INFO - PROCESS | 1980 | _x_x_mozilla_page_load 06:15:34 INFO - PROCESS | 1980 | _x_x_mozilla_page_load_details 06:15:34 INFO - PROCESS | 1980 | |i|pagename|runs| 06:15:34 INFO - PROCESS | 1980 | |0;ai-astar;165;171;114;115;118;118;108;116;114;117 06:15:34 INFO - PROCESS | 1980 | |1;audio-beat-detection;155;122;146;162;124;139;129;148;155;121 06:15:34 INFO - PROCESS | 1980 | |2;audio-dft;196;217;224;198;195;199;197;197;205;196 06:15:34 INFO - PROCESS | 1980 | |3;audio-fft;61;74;74;146;58;55;56;64;127;56 06:15:34 INFO - PROCESS | 1980 | |4;audio-oscillator;103;71;134;115;115;122;146;78;79;126 06:15:34 INFO - PROCESS | 1980 | |5;imaging-gaussian-blur;89;88;89;94;126;87;91;88;89;86 06:15:34 INFO - PROCESS | 1980 | |6;imaging-darkroom;110;135;95;114;150;95;95;94;95;100 06:15:34 INFO - PROCESS | 1980 | |7;imaging-desaturate;150;144;90;90;95;89;88;90;108;148 06:15:34 INFO - PROCESS | 1980 | |8;json-parse-financial;62;64;64;68;64;64;64;81;62;60 06:15:34 INFO - PROCESS | 1980 | |9;json-stringify-tinderbox;71;100;98;111;89;75;79;74;71;84 06:15:34 INFO - PROCESS | 1980 | |10;stanford-crypto-aes;78;82;83;80;80;83;81;84;84;85 06:15:34 INFO - PROCESS | 1980 | |11;stanford-crypto-ccm;137;152;129;135;129;125;156;153;169;176 06:15:34 INFO - PROCESS | 1980 | |12;stanford-crypto-pbkdf2;181;169;168;168;169;165;166;169;163;171 06:15:34 INFO - PROCESS | 1980 | |13;stanford-crypto-sha256-iterative;68;69;86;99;101;100;80;71;68;68 06:15:34 INFO - PROCESS | 1980 | __end_tp_report 06:15:34 INFO - PROCESS | 1980 | __start_cc_report 06:15:34 INFO - PROCESS | 1980 | _x_x_mozilla_cycle_collect,1087 06:15:34 INFO - PROCESS | 1980 | __end_cc_report 06:15:34 INFO - PROCESS | 1980 | __startTimestamp1462799734728__endTimestamp 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | ------- Summary: start ------- 06:15:34 INFO - PROCESS | 1980 | Number of tests: 14 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | [#0] ai-astar Cycles:10 Average:125.60 Median:117.50 stddev:22.57 (19.2%) stddev-sans-first:18.91 06:15:34 INFO - PROCESS | 1980 | Values: 165.0 171.0 114.0 115.0 118.0 118.0 108.0 116.0 114.0 117.0 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:147.00 stddev:15.26 (10.4%) stddev-sans-first:15.21 06:15:34 INFO - PROCESS | 1980 | Values: 155.0 122.0 146.0 162.0 124.0 139.0 129.0 148.0 155.0 121.0 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | [#2] audio-dft Cycles:10 Average:202.40 Median:198.50 stddev:10.07 (5.1%) stddev-sans-first:10.41 06:15:34 INFO - PROCESS | 1980 | Values: 196.0 217.0 224.0 198.0 195.0 199.0 197.0 197.0 205.0 196.0 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | [#3] audio-fft Cycles:10 Average:77.10 Median:59.50 stddev:32.37 (54.4%) stddev-sans-first:33.81 06:15:34 INFO - PROCESS | 1980 | Values: 61.0 74.0 74.0 146.0 58.0 55.0 56.0 64.0 127.0 56.0 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | [#4] audio-oscillator Cycles:10 Average:108.90 Median:140.00 stddev:25.53 (18.2%) stddev-sans-first:26.99 06:15:34 INFO - PROCESS | 1980 | Values: 103.0 71.0 134.0 115.0 115.0 122.0 146.0 78.0 79.0 126.0 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | [#5] imaging-gaussian-blur Cycles:10 Average:92.70 Median:89.00 stddev:11.91 (13.4%) stddev-sans-first:12.55 06:15:34 INFO - PROCESS | 1980 | Values: 89.0 88.0 89.0 94.0 126.0 87.0 91.0 88.0 89.0 86.0 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | [#6] imaging-darkroom Cycles:10 Average:108.30 Median:94.50 stddev:19.63 (20.8%) stddev-sans-first:20.81 06:15:34 INFO - PROCESS | 1980 | Values: 110.0 135.0 95.0 114.0 150.0 95.0 95.0 94.0 95.0 100.0 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | [#7] imaging-desaturate Cycles:10 Average:109.20 Median:89.50 stddev:26.97 (30.1%) stddev-sans-first:24.23 06:15:34 INFO - PROCESS | 1980 | Values: 150.0 144.0 90.0 90.0 95.0 89.0 88.0 90.0 108.0 148.0 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | [#8] json-parse-financial Cycles:10 Average:65.30 Median:64.00 stddev:5.89 (9.2%) stddev-sans-first:6.12 06:15:34 INFO - PROCESS | 1980 | Values: 62.0 64.0 64.0 68.0 64.0 64.0 64.0 81.0 62.0 60.0 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | [#9] json-stringify-tinderbox Cycles:10 Average:85.20 Median:77.00 stddev:13.89 (18.0%) stddev-sans-first:13.75 06:15:34 INFO - PROCESS | 1980 | Values: 71.0 100.0 98.0 111.0 89.0 75.0 79.0 74.0 71.0 84.0 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:83.00 stddev:2.21 (2.7%) stddev-sans-first:1.81 06:15:34 INFO - PROCESS | 1980 | Values: 78.0 82.0 83.0 80.0 80.0 83.0 81.0 84.0 84.0 85.0 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | [#11] stanford-crypto-ccm Cycles:10 Average:146.10 Median:152.50 stddev:17.75 (11.6%) stddev-sans-first:18.52 06:15:34 INFO - PROCESS | 1980 | Values: 137.0 152.0 129.0 135.0 129.0 125.0 156.0 153.0 169.0 176.0 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.90 Median:169.00 stddev:4.84 (2.9%) stddev-sans-first:2.46 06:15:34 INFO - PROCESS | 1980 | Values: 181.0 169.0 168.0 168.0 169.0 165.0 166.0 169.0 163.0 171.0 06:15:34 INFO - PROCESS | 1980 | 06:15:34 INFO - PROCESS | 1980 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.00 Median:70.00 stddev:14.38 (20.5%) stddev-sans-first:14.47 06:15:34 INFO - PROCESS | 1980 | Values: 68.0 69.0 86.0 99.0 101.0 100.0 80.0 71.0 68.0 68.0 06:15:34 INFO - PROCESS | 1980 | -------- Summary: end -------- 06:15:34 INFO - PROCESS | 1980 | 06:15:35 INFO - TEST-INFO | 1980: exit 0 06:15:35 INFO - TEST-OK | kraken | took 59754ms 06:15:35 INFO - SUITE-END | took 1171s 06:15:35 INFO - Completed test suite (00:19:31) 06:15:35 INFO - javascript benchmark 06:15:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3411.965880341196, 3481.1297175706068, 3499.160201551625, 3504.877329293475, 3517.982410087949, 5772.884542309152, 5789.681567513785, 5806.825795226145, 5842.386549412315, 5843.970780146104, 5822.330432000321, 5849.912251316234, 5897.557683173758, 5904.763809447617, 5927.673977931223, 5245.0, 5315.521603055724, 5356.598255130881, 5358.3569971603465, 5392.245085688007, 5231.267622532849, 5330.600204984622, 5344.305240318753, 5379.569634429236, 5384.973075134619, 5317.0, 5352.91970620441, 5357.4106848246665, 5370.060239458098, 5378.273933019051, 5314.202869569576, 5348.0, 5348.197770334442, 5360.0, 5371.355437347524, 5182.067227898977, 5267.446918073624, 5271.499207575274, 5291.920621190685, 5355.491228333302, 5711.029125048752, 5745.1669507921115, 5769.769209231626, 5775.653460792366, 5796.391378905197, 6139.201903752528, 6244.5316601254635, 6267.905981410283, 6296.1500197473215, 6333.493320534346, 6185.783497577563, 6226.533010024221, 6268.937310626881, 6283.214598175228, 6293.244810622755, 5971.353038698756, 5996.550258730569, 6005.249343832021, 6008.759649614054, 6058.545609079337, 6382.776602818879, 6387.712552935129, 6407.0, 6411.2627047889155, 6422.325655806167, 6121.422488707842, 6135.570510064342, 6210.4100110489435, 6266.561340706106, 6286.339934306923, 6244.968775156095, 6268.09112678659, 6287.2140982377205, 6287.842803929939, 6292.559520833498, 6208.50331973441, 6244.188255526752, 6284.4344009039405, 6289.213848268966, 6297.055441683784, 6241.7191226394925, 6258.342873998165, 6270.341614130451, 6274.6548939807835, 6301.4328710416285, 4956.4547899731, 4971.130052240844, 4985.177445721415, 5024.974875125674, 5034.395872495251, 4376.693631445768, 4389.868303950887, 4511.255642818898, 4528.298113792367, 4537.75042372666, 5801.158831969426, 5827.213326200908, 5831.737571809279, 5835.533157347402, 5901.3508514063415, 6258.405451482102, 6267.2479302484, 6271.717772700239, 6276.560640755103, 6280.9685951569945, 5701.629394089454, 5787.623804452697, 5834.620749651259, 5850.473457388855, 5883.970580147157, 5439.401665816757, 5451.509364157245, 5455.481729235717, 5468.398476167618, 5495.862603434866, 6412.935870641327, 6454.677266136769, 6470.352964703586, 6485.837854053686, 6496.187976502937, 6202.34875338101, 6211.503079753699, 6230.252369715663, 6279.0, 6291.1192433058495, 6486.091947127312, 6501.804945851632, 6502.187226596676, 6520.771772988112, 6535.640539770358, 5434.858679677154, 6233.625982441068, 6300.527460440394, 6309.779157729641, 6310.495160387066, 6065.939340606537, 6123.142760013513, 6174.043023331391, 6214.937850621617, 6231.158793562811, 6167.259928808572, 6192.071189321638, 6222.564420490522, 6239.313675495601, 6241.812745617639, 6089.421504957022, 6168.1672974149715, 6263.68681565929, 6275.152854364603, 6290.21372328459, 5132.2814805926455, 5624.634398764068, 5977.103434484862, 6037.18498002764, 6166.260048794174, 6194.566380353332, 6201.565890387629, 6228.128062071404, 6239.06414037898, 6243.469305109116, 5969.224000880032, 6098.176746139215, 6139.171211886338, 6151.138840562236, 6181.103739957591], "value": 5790.6434073518885, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.3461327208269, 511.9683837068308, 525.1597444089456, 536.0725944116678, 557.8410153106365, 35237.0, 40373.192536149334, 40380.394294085614, 40422.797886010565, 40427.59572404279, 41698.0, 42144.789276053576, 42234.944126396855, 42286.577134228726, 42411.78794106033, 9393.624255029808, 9566.712998610054, 9615.56729947154, 9621.711348659517, 9650.276229282797, 9506.667266645634, 9739.561719722595, 9755.317127801056, 9768.413895166277, 9778.315517913748, 9717.076877696607, 9747.805043899118, 9758.756031099243, 9776.0, 9793.26550508711, 9686.75783105421, 9756.268279879037, 9761.365511241747, 9791.363561368487, 9791.902080979171, 8372.748817535483, 9672.90327096727, 9770.0, 9787.657431989846, 9792.118709316124, 40612.593874061175, 40742.79628601838, 41187.58812411867, 41224.79387603073, 41236.38145427821, 3232.531282963957, 3309.7352211822995, 3323.0363194673514, 3460.325236578868, 3666.6699997000396, 29079.273018174503, 29276.853615731998, 29285.70714292851, 29986.700132998827, 30034.399312013855, 2795.8741856616502, 2801.705820888798, 2822.5483922572494, 2868.4836729388703, 2869.1105757215123, 467376.0, 470828.0, 474788.62605687097, 475809.0, 476911.0, 36763.0, 66004.33995660079, 66382.33617663858, 66519.0, 66679.66640667159, 37688.0, 38048.809755951595, 38159.85520434391, 38197.42703859444, 38221.42667859984, 16000.919995399949, 16045.59886002836, 16314.918425407797, 16338.346466141458, 16356.0, 13705.0, 13819.792703109462, 13821.585352439442, 13822.654433639038, 13824.239666818421, 8319.500829950222, 8364.749057528285, 8447.577621118919, 8479.321654267644, 8494.447860889022, 3010.8795648174264, 3432.2449061206494, 3489.74911182286, 3499.650034996531, 3600.8657272958935, 854.679495189304, 886.9135309246251, 961.403929563661, 978.161057212436, 1028.6502589119734, 25459.61810572843, 26644.934202632066, 26680.466390672078, 28716.71283287182, 28880.711192888222, 1012.1750773119884, 1066.3069005146717, 1087.7335052912088, 1095.7644106517137, 1149.3793351590216, 14422.711545769236, 14735.336909839083, 14744.63138421548, 14992.250387480583, 15046.32291546883, 606738.0, 612883.0, 616777.0, 620989.0, 623090.0, 63592.0, 64308.035379469344, 65151.348486515475, 65234.0, 65248.36879078061, 1471.7350876842056, 1476.4168153579492, 1507.5175943697911, 1513.9243037848064, 1529.3576697786957, 24068.19687813011, 28303.433931320847, 28601.42797144004, 28745.0, 28864.42271154523, 54627.0, 55319.72340138273, 55601.721991389786, 56056.15915761348, 56381.0, 545940.0, 559810.0, 559822.0, 565405.0, 565772.0, 27675.169744907686, 28153.859230703714, 28583.857080714464, 44201.33697994468, 45391.31913021241, 44937.101257974005, 45171.774141129084, 45703.77148114238, 45837.08325833531, 46067.84830379267, 36923.261534768615, 37826.24347513089, 40127.19745605013, 40183.0, 41293.96765080897, 30994.0, 33088.0, 35743.64256357507, 35828.462573060904, 36109.81945090258, 36402.0, 36505.45241821428, 38596.421053683654, 39985.0, 39997.80006599807, 31430.528542072345, 32759.344813104082, 32791.18022049468, 32914.0, 32998.67001329956, 19326.806731932502, 20072.397828065183, 23550.175743848882, 24220.394490137183, 24700.752992469847], "value": 18808.96920584571, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.06660613981694, 405.38381659876967, 407.7553467919248, 408.97750623715683, 417.111552393402, 14781.852181478182, 20099.899500502495, 20705.0, 20855.27006554771, 20900.790992090075, 18684.439466816017, 20129.29547465839, 20372.89813550934, 20500.69248961267, 21252.89373553134, 4853.563179313852, 5787.594868359217, 6083.239595050619, 6087.178230938833, 6090.482309003718, 5971.820845374624, 6014.338422773491, 6028.336882942873, 6105.664188469633, 6106.603070800406, 6076.2708474983065, 6086.878262434749, 6090.5736598438125, 7175.138983322008, 7620.809479763023, 7427.517211381297, 7517.736879209201, 7535.0, 7659.463837531375, 7934.603269836485, 7708.65311060998, 7737.187595302525, 7744.2642948919765, 7749.496282741604, 7771.261730135628, 29329.853350733323, 30366.69633303661, 30399.848000760077, 30716.692833071607, 31319.0, 2275.9317220483413, 2442.987785061081, 2529.607910773833, 2545.0583284184786, 2557.9872100639564, 15795.921020394939, 15820.288087036111, 15848.841511584968, 16100.516984490485, 16554.751678724828, 1893.848492120627, 1928.4311128217068, 1930.691089425685, 1933.9806601933942, 1979.7228388025687, 117436.0, 118165.0, 126489.0, 134197.32901335528, 135000.9749853743, 19786.70319945202, 20523.076461559267, 22172.223972160897, 22425.10299588031, 22534.21130260433, 32403.35193296121, 34004.82997584996, 35817.0, 36019.09952251162, 36892.63107368946, 11366.147538934612, 11655.76688466243, 11691.76616467666, 11721.062315014779, 11856.70358241051, 9543.188828949616, 9549.0, 9565.473898935625, 9658.985806490358, 9672.08115229052, 9187.632494700138, 10339.327943683787, 10455.0, 10536.894631053745, 10633.627823026312, 3997.6801855851463, 4079.755214687128, 4117.341225403921, 4194.8112334945, 4307.5261721210645, 797.8643630582815, 808.1433680298902, 863.0980625246632, 894.2577660541734, 923.459776031016, 17440.912795436194, 19074.04629768506, 19830.603387932162, 19920.900395498218, 20022.299219527245, 896.9237614802685, 915.2357781252729, 919.8068405634758, 980.3333733061587, 985.7387792235063, 10092.021941777539, 10408.843867341995, 10413.42726150069, 10439.37363758177, 10578.206634502443, 616214.0, 623120.0, 635533.0, 642911.7854410792, 643862.0, 113060.0, 115456.0, 116762.0, 118032.40983795025, 119808.0, 977.8533220017055, 985.3595163144014, 990.3166814897697, 997.5411310796969, 1023.0741179232806, 17255.309787608858, 17394.5651358712, 17634.470965871045, 18194.90902545479, 19380.90309548443, 50599.74700126476, 52613.947721046134, 53251.467485324654, 54540.727296363264, 55736.44263557313, 67860.0, 70238.0, 72303.0, 72905.6354718223, 74681.0, 8003.599820009093, 8147.79630509242, 8156.898818659405, 8284.751457456287, 8456.957715211385, 11439.942800285946, 12770.61688149357, 12821.974242060427, 13024.218546887218, 13055.673608159872, 7711.843223516517, 10106.090451859369, 10513.78972420532, 10672.679819605424, 10733.195010374096, 5089.8727531812, 8613.526256055977, 9797.922228554995, 10818.350898946088, 10940.288881222696, 10792.08267297257, 10827.0, 10849.349039057683, 11194.888051119384, 11271.605493807678, 8658.696945606873, 11673.241239319417, 12008.459619317326, 12260.75478490443, 12477.251364918135, 9575.09036641518, 10860.565577377132, 11226.382548959558, 11337.546498139982, 11398.829017564578], "value": 10920.839767559874, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1477.1580199286404, 1578.889477736558, 1777.5289548269711, 1864.8601354898378, 1909.8090190980913, 7967.20327967203, 8607.354448416363, 8789.208971192591, 9021.007689154196, 9026.4132831366, 6439.581427207228, 8697.608607612658, 8748.737537873858, 8815.603297851596, 9150.90849091509, 2007.6988451732284, 2389.665446837444, 2453.472503411766, 2484.1181380609896, 2488.129154795825, 2462.729099799021, 2476.0590975429313, 2514.585093459577, 2515.9874200629065, 2516.471540976397, 2465.568525508038, 2490.8256422050463, 2503.436726736488, 2522.369407648088, 2525.154073385418, 2471.938201544958, 2512.196097248881, 2522.155078048856, 2522.5081109183716, 2524.798016158721, 2485.751424857518, 2490.1284550407213, 2512.4095837478176, 2515.8238923275376, 2522.735112813165, 8839.77900552485, 8899.73300800977, 8911.821763564758, 8925.776855578597, 8933.41932774374, 5457.99027179973, 5532.170174473821, 5544.667319960775, 5566.832995010116, 5624.3250809902665, 3540.7344449166417, 3541.4687796830426, 3591.317649646559, 3695.9630403696156, 3715.6655900969045, 1055.0508702931431, 1070.925035247533, 1071.8582522148058, 1073.7047311989188, 1086.9456527173609, 11937.940310298392, 12152.270863748203, 12154.149209555335, 12221.144519883612, 12242.510299588095, 4894.779734911866, 4919.975400122977, 4956.3804524434445, 5006.299118123466, 5020.347354843847, 10235.488225588691, 10307.896921030693, 10311.896881031244, 10313.226508011929, 10328.431936243429, 4643.442786865598, 5509.146082357241, 5572.944270557243, 5584.0, 5616.887662246814, 4465.620422264144, 4882.340883980688, 5001.724905130252, 5037.345145131109, 5059.97470012655, 4152.8131234094535, 4157.91684166315, 4212.726172798758, 4241.618254357123, 4253.48958125027, 1173.3077484284313, 1369.6096612465399, 1384.210999730144, 1396.441423430636, 1401.4114072089747, 834.4479268560473, 905.3210092430677, 906.9501177435304, 919.2462181011506, 922.8431166701542, 5719.084946408555, 5801.274840644919, 5821.7380217890295, 5823.767049318065, 5910.320313163955, 431.20873197681976, 443.60740744441404, 455.3647661512238, 472.3269341188792, 477.238804107455, 3268.297316077055, 3729.6083911188935, 3770.321342158438, 3779.7354185207323, 3782.6028267031566, 10524.684259472227, 10810.459477026117, 10900.182486313559, 10936.945315273373, 10960.56157753681, 10526.842097368546, 10650.89349106499, 10737.355758654505, 10810.513526891309, 10858.34849909008, 8362.8327433451, 8369.62336694838, 8461.661533538589, 8462.02686691026, 8506.064332923374, 5397.244385786072, 5589.524890384363, 5607.355154157324, 5681.630694004876, 5712.143178523254, 9310.0, 9583.712488625353, 9598.568064437257, 9603.519824008632, 9731.17285030752, 9415.435073895589, 9745.561449734638, 9912.00879912003, 9935.105840474393, 10035.498225088862, 6835.487338449537, 7311.488195826241, 7491.250874912465, 7517.43619228571, 7611.4291428144215, 8279.13069127757, 8289.04675962272, 8312.21034001769, 8326.542040187847, 8348.290395316466, 7006.789796306119, 7409.66656500448, 7422.888656670047, 7457.850842983002, 7525.247475252431, 6193.597416167935, 7334.633268336668, 7352.0, 7361.595112268662, 7425.554466732014, 6899.48253880971, 6917.066196063467, 6937.82655433598, 6938.410235130071, 6951.582905025714, 4736.384270044872, 4763.999560092419, 4805.5915247204375, 4851.369321988119, 4862.513748625109, 4846.539578740014, 4882.951170488392, 4912.680675756064, 4999.350084488993, 5047.823326183566], "value": 4549.955791315813, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.58324178567864, 321.080105497749, 327.217949101647, 328.2515863830466, 332.0271604199697, 6784.966075169617, 6785.355391237837, 6792.796216113518, 6813.182418109822, 6821.386075253226, 6791.117154769873, 6817.761378351759, 6825.931740682592, 6828.2488926218075, 6859.0, 4846.709197448146, 4888.828890988798, 5028.371453568304, 5031.597472202215, 5033.521815427547, 3843.7117216208894, 4413.161499315136, 4969.602431805447, 5011.32347133136, 5032.144535428967, 4053.918921621566, 4959.0, 5026.346574945252, 5028.209343814182, 5035.974820125894, 4519.276915693474, 5012.3483947086825, 5029.497050294978, 5048.848534543969, 5050.065737838511, 3535.7171426285836, 4004.7196696231276, 4323.178596066738, 4342.5874541918465, 4354.477462704495, 5860.853478663025, 6476.996065609838, 6563.081168636395, 6653.53425260232, 6750.594964302109, 4821.662483626147, 5930.584859059867, 5979.671118088503, 5996.550258730569, 6005.939940600581, 6016.729247183887, 7097.609631470267, 7280.708771649128, 7304.744333948286, 7305.963470182668, 4820.734859582722, 4821.059893320804, 4825.662203645747, 4827.58965487934, 4838.975805120952, 7698.0, 7705.267999540035, 7720.498167619143, 7727.304542591138, 7737.9226207737765, 3913.021744563859, 4190.664746820308, 4740.19416699155, 4789.85043589543, 4798.160321943716, 3963.6432721055244, 4876.853694389174, 4887.535684110004, 4887.73117478535, 4893.703168660308, 5853.239078919798, 5863.794767183128, 5877.353491115974, 5882.411758824169, 5892.086726557391, 7356.264373562601, 7379.262073792578, 7394.26057394267, 7395.445341599401, 7419.332260096509, 1396.3506969259342, 1667.1997441228277, 1683.7811084559173, 1695.3049249807766, 1698.431025606411, 1060.6552870317023, 1081.4106312059946, 1103.2828661370174, 1140.5152810055858, 1146.2893006335958, 6235.2564871457325, 6641.634710090895, 6712.96643516789, 6742.83142921421, 6762.323767623198, 6153.0, 7333.266673332624, 7590.3927685785, 7594.848103037908, 7628.466007379541, 6641.535092543476, 6751.4261287790105, 6825.69284382204, 6845.281245469254, 6849.109615750021, 3582.659647333499, 3821.7133714971487, 4046.968023154077, 4057.107436363995, 4083.4283200351974, 8060.798480038046, 8075.313598344089, 8096.554689492016, 8105.878411823827, 8116.959415202886, 7513.985611942428, 7530.28462296081, 7925.0886148093705, 7954.164812694699, 7959.0, 8026.558539280396, 8084.959575202204, 8091.231333023354, 8093.959530202312, 8101.837963240702, 7662.578558179353, 7683.039620047494, 7711.730089446843, 7714.305712485679, 7714.56701767708, 7512.774616761506, 7551.0, 7554.357879580078, 7556.131044929683, 7571.621418929141, 7780.2219778021745, 8033.8393232136195, 8089.676412943416, 8104.189581041848, 8106.310963568163, 4948.0, 4994.0, 4999.500049994971, 5002.0, 5028.547430731252, 7639.732609358646, 7646.770596882103, 7666.425018123551, 7675.808104797425, 7693.769186924401, 6745.325467453215, 7371.299726525972, 7383.446241532014, 7393.482456228013, 7419.8145046374275, 5084.491550845034, 6470.579412338183, 7349.265073492608, 7397.3342399182175, 7420.703171873065, 5398.757055932414, 7147.7491438999, 7190.7483238086415, 7226.313500217471, 7229.421646268391, 6070.816190842744, 7378.741744038933, 7379.446541509301, 7395.186529481645, 7418.443616728659, 6982.511224214256, 7215.819604509929, 7240.203577606354, 7244.384227340524, 7253.347198752138], "value": 5287.434191196978, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.6402002968472, 709.2481969112738, 737.7749786315178, 743.5538676793923, 757.1293013035009, 15070.39718411265, 15083.849161508395, 15286.923565382185, 15351.53945381639, 17044.318227270924, 15555.84444155558, 16070.5982350441, 16109.677806443864, 16193.433229836928, 16301.755473667905, 738.8780851159552, 906.5421961909243, 917.1516347378648, 926.106307413346, 926.9165775080241, 931.3154831199063, 933.439936038375, 935.1817159985013, 936.1948724097305, 937.3298091864316, 935.508857849631, 967.4823969176492, 973.0, 973.5618971462835, 974.770928831724, 959.3955807841015, 963.8939716631165, 968.9321747477679, 969.3263182088451, 970.0105891990202, 694.90966174397, 720.3912693773752, 723.0166972916829, 726.6366816591704, 728.4864170759655, 15124.848751512454, 15240.466583669519, 15418.614534636723, 15484.148371839545, 15504.30230639624, 12386.504539818396, 12406.379681016004, 12451.377431128498, 12512.124151309412, 12552.43514041861, 8704.521251331174, 10098.747531311703, 10273.434961077137, 10397.948010259975, 10487.895121048768, 3270.591176102987, 3308.0902751743224, 3309.7517686173633, 3359.1266270769534, 3386.5936087669393, 77538.22461775366, 78256.60871695718, 78439.0, 80070.59964700139, 81412.59293703553, 9438.811223775487, 9455.479948602755, 9512.857307140399, 9542.475163865915, 9678.70963871085, 20181.293654722012, 20763.68854467184, 20902.686459703116, 20916.895415522824, 20966.0, 7289.198188199294, 7335.559866408033, 7423.9257607424315, 7498.5125966813075, 7500.474966752275, 3473.6179020307745, 4096.91806163881, 4119.938200926988, 4144.979275103666, 4163.479565054368, 3592.7125829933543, 3665.1020499977676, 3672.779633222015, 3673.9265214695556, 3676.852925882935, 503.7808553279335, 588.8380695308739, 599.7091410665823, 652.9389741669787, 668.3583759590847, 542.7421974562052, 557.6932687022203, 561.5142901390268, 567.3617180671745, 618.3445547719432, 6729.831754206184, 7773.378129749606, 7855.803604909809, 7915.445918785745, 7952.801179970431, 580.613891761982, 589.7169358707845, 600.1388008208235, 607.2561112637072, 609.2049874913192, 3657.6159503252306, 3719.8512059517857, 3750.081230098643, 3764.4917936078828, 3802.8098595070137, 93020.0, 93895.65260868396, 93943.53028234815, 94021.0, 94351.0, 74975.0, 75377.62311188519, 77119.61440192763, 77780.83328750073, 80114.0, 69388.0, 69684.0, 70354.64822675854, 70685.64657176682, 71557.2844271561, 7029.156501219682, 7383.778486645409, 7414.147373052168, 7443.776686699408, 7771.922280777346, 45601.77199114116, 47210.76394618005, 47279.29081063856, 47324.526754733386, 47553.0, 61278.38721612727, 65535.01697474637, 66513.66972660423, 67166.0, 67185.66407167933, 9983.60065597397, 10077.798444031225, 10139.391636501834, 10149.188064954638, 10173.898261017295, 10701.732456688645, 10796.298240614335, 10920.563177472812, 11002.614908478165, 11585.420728963687, 9776.804463910825, 9951.452670103214, 10046.748831279276, 10196.235282353704, 10451.059404653617, 9665.081817227352, 9692.273079519206, 9746.805063898824, 9819.410835349901, 10216.948915255674, 9974.850377244202, 10217.642382516577, 10803.405812680063, 11062.39156846349, 11133.944330278297, 9558.761030974281, 9588.712338629852, 9691.2731545133, 9730.41617502952, 9977.20182385393, 9462.668806591737, 9464.716058518256, 9529.57116929726, 9556.90443095588, 9565.234781217347], "value": 6772.72486832251, "unit": "score"}], "name": "dromaeo_css", "value": 7607.350223496271, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 171.0, 114.0, 115.0, 118.0, 118.0, 108.0, 116.0, 114.0, 117.0], "value": 125.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 122.0, 146.0, 162.0, 124.0, 139.0, 129.0, 148.0, 155.0, 121.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 217.0, 224.0, 198.0, 195.0, 199.0, 197.0, 197.0, 205.0, 196.0], "value": 202.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 74.0, 74.0, 146.0, 58.0, 55.0, 56.0, 64.0, 127.0, 56.0], "value": 77.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 71.0, 134.0, 115.0, 115.0, 122.0, 146.0, 78.0, 79.0, 126.0], "value": 108.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 89.0, 94.0, 126.0, 87.0, 91.0, 88.0, 89.0, 86.0], "value": 92.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 135.0, 95.0, 114.0, 150.0, 95.0, 95.0, 94.0, 95.0, 100.0], "value": 108.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 144.0, 90.0, 90.0, 95.0, 89.0, 88.0, 90.0, 108.0, 148.0], "value": 109.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 64.0, 68.0, 64.0, 64.0, 64.0, 81.0, 62.0, 60.0], "value": 65.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 100.0, 98.0, 111.0, 89.0, 75.0, 79.0, 74.0, 71.0, 84.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 82.0, 83.0, 80.0, 80.0, 83.0, 81.0, 84.0, 84.0, 85.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 152.0, 129.0, 135.0, 129.0, 125.0, 156.0, 153.0, 169.0, 176.0], "value": 146.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 169.0, 168.0, 168.0, 169.0, 165.0, 166.0, 169.0, 163.0, 171.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 86.0, 99.0, 101.0, 100.0, 80.0, 71.0, 68.0, 68.0], "value": 81.0, "unit": "score"}], "name": "kraken", "value": 1592.8000000000002, "alertThreshold": 2.0}]} 06:15:35 INFO - Return code: 0 06:15:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:15:36 ERROR - # TBPL SUCCESS # 06:15:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:15:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:15:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:15:36 INFO - 2016-05-09 06:15:36.019 screenresolution[1985:15143] starting screenresolution argv=screenresolution get 06:15:36 INFO - 2016-05-09 06:15:36.022 screenresolution[1985:15143] Display 0: 1600x1200x32@60 06:15:36 INFO - 2016-05-09 06:15:36.028 screenresolution[1986:15146] starting screenresolution argv=screenresolution list 06:15:36 INFO - Available Modes on Display 0 06:15:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:15:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:15:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:15:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:15:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:15:36 INFO - Intel Iris: 06:15:36 INFO - Chipset Model: Intel Iris 06:15:36 INFO - Type: GPU 06:15:36 INFO - Bus: Built-In 06:15:36 INFO - VRAM (Dynamic, Max): 1536 MB 06:15:36 INFO - Vendor: Intel (0x8086) 06:15:36 INFO - Device ID: 0x0a2e 06:15:36 INFO - Revision ID: 0x0009 06:15:36 INFO - Displays: 06:15:36 INFO - Display: 06:15:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:15:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:15:36 INFO - Display Serial Number: 1600x1200 60 06:15:36 INFO - Main Display: Yes 06:15:36 INFO - Mirror: Off 06:15:36 INFO - Online: Yes 06:15:36 INFO - Rotation: Supported 06:15:36 INFO - Return code: 0 06:15:36 INFO - Running post-action listener: _resource_record_post_action 06:15:36 INFO - Running post-run listener: _resource_record_post_run 06:15:37 INFO - Total resource usage - Wall time: 1205s; CPU: 54.0%; Read bytes: 2084864; Write bytes: 654707712; Read time: 17; Write time: 1962 06:15:37 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345010688; Write bytes: 345789952; Read time: 26401; Write time: 631 06:15:37 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 2084864; Write bytes: 297729536; Read time: 17; Write time: 1279 06:15:37 INFO - Running post-run listener: _upload_blobber_files 06:15:37 INFO - Blob upload gear active. 06:15:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:15:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:15:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:15:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:15:37 INFO - (blobuploader) - INFO - Open directory for files ... 06:15:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:15:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:15:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:38 INFO - (blobuploader) - INFO - Done attempting. 06:15:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:15:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:15:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:39 INFO - (blobuploader) - INFO - Done attempting. 06:15:39 INFO - (blobuploader) - INFO - Iteration through files over. 06:15:39 INFO - Return code: 0 06:15:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:15:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:15:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f850adfc2a89b8c25e1ecef571afd5689b2e164454b8c15d483365e6bfec2b45aa8df9330f0696745153a3f020be89f1ae607daa8926b9ec88fef1febae7d4f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7dad7c91000b7c3f3e40da1337a5f3c6202853afe2e8b23edc9ef2aec4bfb6d6a4e33315f8b84ec2b320bff3e2b196b6f5ec5b8b75f00a3ec712dd44e2ce6ee2"} 06:15:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:15:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:15:39 INFO - Contents: 06:15:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f850adfc2a89b8c25e1ecef571afd5689b2e164454b8c15d483365e6bfec2b45aa8df9330f0696745153a3f020be89f1ae607daa8926b9ec88fef1febae7d4f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7dad7c91000b7c3f3e40da1337a5f3c6202853afe2e8b23edc9ef2aec4bfb6d6a4e33315f8b84ec2b320bff3e2b196b6f5ec5b8b75f00a3ec712dd44e2ce6ee2"} 06:15:39 INFO - Running post-run listener: copy_logs_to_upload_dir 06:15:39 INFO - Copying logs to upload dir... 06:15:39 INFO - mkdir: /builds/slave/test/build/upload/logs 06:15:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.424514 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-05-09 06:15:39.443609) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 06:15:39.450907) ========= 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.IdoKgSnjyz/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.BVcwKUdqFG/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f850adfc2a89b8c25e1ecef571afd5689b2e164454b8c15d483365e6bfec2b45aa8df9330f0696745153a3f020be89f1ae607daa8926b9ec88fef1febae7d4f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7dad7c91000b7c3f3e40da1337a5f3c6202853afe2e8b23edc9ef2aec4bfb6d6a4e33315f8b84ec2b320bff3e2b196b6f5ec5b8b75f00a3ec712dd44e2ce6ee2"} build_url:https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011561 build_url: 'https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f850adfc2a89b8c25e1ecef571afd5689b2e164454b8c15d483365e6bfec2b45aa8df9330f0696745153a3f020be89f1ae607daa8926b9ec88fef1febae7d4f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7dad7c91000b7c3f3e40da1337a5f3c6202853afe2e8b23edc9ef2aec4bfb6d6a4e33315f8b84ec2b320bff3e2b196b6f5ec5b8b75f00a3ec712dd44e2ce6ee2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 06:15:39.481631) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 06:15:39.481917) ========= 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.IdoKgSnjyz/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.BVcwKUdqFG/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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.005152 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 06:15:39.529028) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 06:15:39.529300) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 06:15:39.532596) ========= ========= Total master_lag: 0.18 =========