builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0034 starttime: 1462786136.92 results: success (0) buildid: 20160509005049 builduid: 41dbd660830c4270b4520649f25740b3 revision: 307b5eca8726f6a72a1d69ba76630a6dbf402477 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 02:28:56.915464) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 02:28:56.915871) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 02:28:56.916139) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 02:28:56.935896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 02:28:56.936204) ========= 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.NyAB9RrLb5/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.dbBAjgculM/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 02:28:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.30M=0.001s 2016-05-09 02:28:57 (9.30 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081869 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 02:28:57.036136) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 02:28:57.036462) ========= 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.NyAB9RrLb5/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.dbBAjgculM/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.030353 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 02:28:57.085363) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 02:28:57.085726) ========= 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.NyAB9RrLb5/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.dbBAjgculM/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 02:28:57,160 truncating revision to first 12 chars 2016-05-09 02:28:57,160 Setting DEBUG logging. 2016-05-09 02:28:57,161 attempt 1/10 2016-05-09 02:28:57,161 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 02:28:57,433 unpacking tar archive at: mozilla-aurora-307b5eca8726/testing/mozharness/ program finished with exit code 0 elapsedTime=0.529999 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 02:28:57.633123) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 02:28:57.633424) ========= script_repo_revision: 307b5eca8726f6a72a1d69ba76630a6dbf402477 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 02:28:57.633813) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 02:28:57.634075) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 02:28:57.648203) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-09 02:28:57.648646) ========= /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.NyAB9RrLb5/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.dbBAjgculM/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:28:57 INFO - MultiFileLogger online at 20160509 02:28:57 in /builds/slave/test 02:28:57 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 02:28:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:28:57 INFO - {'append_to_log': False, 02:28:57 INFO - 'base_work_dir': '/builds/slave/test', 02:28:57 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:28:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:28:57 INFO - 'branch': 'Mozilla-Aurora', 02:28:57 INFO - 'buildbot_json_path': 'buildprops.json', 02:28:57 INFO - 'config_files': ('talos/mac_config.py',), 02:28:57 INFO - 'default_actions': ('clobber', 02:28:57 INFO - 'read-buildbot-config', 02:28:57 INFO - 'download-and-extract', 02:28:57 INFO - 'populate-webroot', 02:28:57 INFO - 'create-virtualenv', 02:28:57 INFO - 'install', 02:28:57 INFO - 'run-tests'), 02:28:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:28:57 INFO - 'download_minidump_stackwalk': True, 02:28:57 INFO - 'download_symbols': 'ondemand', 02:28:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:28:57 INFO - 'tooltool.py': '/tools/tooltool.py', 02:28:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:28:57 INFO - '/tools/misc-python/virtualenv.py')}, 02:28:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:28:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:28:57 INFO - 'installer_path': 'installer.exe', 02:28:57 INFO - 'log_level': 'info', 02:28:57 INFO - 'log_name': 'talos', 02:28:57 INFO - 'log_to_console': True, 02:28:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:28:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:28:57 INFO - 'opt_config_files': (), 02:28:57 INFO - 'pip_index': False, 02:28:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:28:57 INFO - 'cmd': ('bash', 02:28:57 INFO - '-c', 02:28:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:28:57 INFO - 'enabled': True, 02:28:57 INFO - 'halt_on_failure': False, 02:28:57 INFO - 'name': 'check_screen_resolution'},), 02:28:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:28:57 INFO - 'cmd': ('bash', 02:28:57 INFO - '-c', 02:28:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:28:57 INFO - 'enabled': True, 02:28:57 INFO - 'halt_on_failure': False, 02:28:57 INFO - 'name': 'check_screen_resolution'},), 02:28:57 INFO - 'run_cmd_checks_enabled': True, 02:28:57 INFO - 'sps_profile': False, 02:28:57 INFO - 'sps_profile_interval': 0, 02:28:57 INFO - 'suite': 'dromaeojs-e10s', 02:28:57 INFO - 'system_bits': '32', 02:28:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:28:57 INFO - 'title': 't-yosemite-r7-0034', 02:28:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:28:57 INFO - 'use_talos_json': True, 02:28:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:28:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:28:57 INFO - 'work_dir': 'build'} 02:28:57 INFO - ##### 02:28:57 INFO - ##### Running clobber step. 02:28:57 INFO - ##### 02:28:57 INFO - Running pre-action listener: _resource_record_pre_action 02:28:57 INFO - Running main action method: clobber 02:28:57 INFO - rmtree: /builds/slave/test/build 02:28:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:28:59 INFO - Running post-action listener: _resource_record_post_action 02:28:59 INFO - ##### 02:28:59 INFO - ##### Running read-buildbot-config step. 02:28:59 INFO - ##### 02:28:59 INFO - Running pre-action listener: _resource_record_pre_action 02:28:59 INFO - Running main action method: read_buildbot_config 02:28:59 INFO - Using buildbot properties: 02:28:59 INFO - { 02:28:59 INFO - "project": "", 02:28:59 INFO - "product": "firefox", 02:28:59 INFO - "script_repo_revision": "production", 02:28:59 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:28:59 INFO - "repository": "", 02:28:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:28:59 INFO - "buildid": "20160509005049", 02:28:59 INFO - "pgo_build": "False", 02:28:59 INFO - "basedir": "/builds/slave/test", 02:28:59 INFO - "buildnumber": 2, 02:28:59 INFO - "slavename": "t-yosemite-r7-0034", 02:28:59 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:28:59 INFO - "platform": "yosemite_r7", 02:28:59 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:28:59 INFO - "branch": "mozilla-aurora", 02:28:59 INFO - "repo_path": "releases/mozilla-aurora", 02:28:59 INFO - "revision": "307b5eca8726f6a72a1d69ba76630a6dbf402477", 02:28:59 INFO - "stage_platform": "macosx64", 02:28:59 INFO - "builduid": "41dbd660830c4270b4520649f25740b3", 02:28:59 INFO - "slavebuilddir": "test" 02:28:59 INFO - } 02:28:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:28:59 INFO - Running post-action listener: _resource_record_post_action 02:28:59 INFO - ##### 02:28:59 INFO - ##### Running download-and-extract step. 02:28:59 INFO - ##### 02:28:59 INFO - Running pre-action listener: _resource_record_pre_action 02:28:59 INFO - Running main action method: download_and_extract 02:28:59 INFO - mkdir: /builds/slave/test/build/tests 02:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:59 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 02:28:59 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 02:28:59 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 02:28:59 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 02:28:59 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 02:29:00 INFO - Downloaded 1199 bytes. 02:29:00 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:29:00 INFO - Using the following test package requirements: 02:29:00 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:29:00 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:29:00 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:29:00 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:29:00 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:29:00 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:29:00 INFO - u'jsshell-mac.zip'], 02:29:00 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:29:00 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:29:00 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:29:00 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:29:00 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:29:00 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:29:00 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:29:00 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:29:00 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:29:00 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:29:00 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:29:00 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:29:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:00 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 02:29:00 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 02:29:00 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 02:29:00 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 02:29:00 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 02:29:01 INFO - Downloaded 18088795 bytes. 02:29:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:29:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:29:05 INFO - Return code: 0 02:29:05 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:29: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')]} 02:29:05 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 02:29:05 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 02:29:05 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 02:29:05 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 02:29:05 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 02:29:06 INFO - Downloaded 18088795 bytes. 02:29:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:29:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:29:10 INFO - Return code: 0 02:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:10 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 02:29:10 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 02:29:10 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 02:29:10 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 02:29:10 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 02:29:11 INFO - Downloaded 10840145 bytes. 02:29:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:29:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:29:11 INFO - Return code: 0 02:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:11 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 02:29:11 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 02:29:11 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 02:29:11 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 02:29:11 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 02:29:14 INFO - Downloaded 114085322 bytes. 02:29:14 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 02:29:14 INFO - mkdir: /builds/slave/test/properties 02:29:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:29:14 INFO - Writing to file /builds/slave/test/properties/build_url 02:29:14 INFO - Contents: 02:29:14 INFO - build_url:https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:29:16 INFO - Running post-action listener: _resource_record_post_action 02:29:16 INFO - Running post-action listener: set_extra_try_arguments 02:29:16 INFO - ##### 02:29:16 INFO - ##### Running populate-webroot step. 02:29:16 INFO - ##### 02:29:16 INFO - Running pre-action listener: _resource_record_pre_action 02:29:16 INFO - Running main action method: populate_webroot 02:29:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:29:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:29:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:29:16 INFO - '--tppagecycles', 02:29:16 INFO - '7'], 02:29:16 INFO - 'tests': ['tsvgm']}}, 02:29:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:29:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:29:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:29:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:29:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:29:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:29:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:16 INFO - 'talos_options': ['--disable-e10s'], 02:29:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:16 INFO - 'talos_options': ['--disable-e10s'], 02:29:16 INFO - 'tests': ['damp', 'tps']}, 02:29:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:16 INFO - 'tests': ['damp', 'tps']}, 02:29:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:29:16 INFO - 'tests': ['dromaeo_dom']}, 02:29:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:29:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:29:16 INFO - 'tests': ['a11yr', 02:29:16 INFO - 'ts_paint', 02:29:16 INFO - 'tpaint', 02:29:16 INFO - 'sessionrestore', 02:29:16 INFO - 'sessionrestore_no_auto_restore', 02:29:16 INFO - 'tabpaint']}, 02:29:16 INFO - 'other-e10s': {'tests': ['a11yr', 02:29:16 INFO - 'ts_paint', 02:29:16 INFO - 'tpaint', 02:29:16 INFO - 'sessionrestore', 02:29:16 INFO - 'sessionrestore_no_auto_restore', 02:29:16 INFO - 'tabpaint']}, 02:29:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:29:16 INFO - 'ts_paint', 02:29:16 INFO - 'tpaint', 02:29:16 INFO - 'sessionrestore', 02:29:16 INFO - 'sessionrestore_no_auto_restore', 02:29:16 INFO - 'tabpaint']}, 02:29:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:29:16 INFO - 'ts_paint', 02:29:16 INFO - 'tpaint', 02:29:16 INFO - 'sessionrestore', 02:29:16 INFO - 'sessionrestore_no_auto_restore', 02:29:16 INFO - 'tabpaint']}, 02:29:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:29:16 INFO - 'tests': ['a11yr', 02:29:16 INFO - 'ts_paint', 02:29:16 INFO - 'tpaint', 02:29:16 INFO - 'sessionrestore', 02:29:16 INFO - 'sessionrestore_no_auto_restore', 02:29:16 INFO - 'tabpaint']}, 02:29:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:29:16 INFO - 'tests': ['a11yr', 02:29:16 INFO - 'ts_paint', 02:29:16 INFO - 'tpaint', 02:29:16 INFO - 'sessionrestore', 02:29:16 INFO - 'sessionrestore_no_auto_restore', 02:29:16 INFO - 'tabpaint']}, 02:29:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:29:16 INFO - 'tests': ['tsvgx', 02:29:16 INFO - 'tsvgr_opacity', 02:29:16 INFO - 'tart', 02:29:16 INFO - 'tscrollx', 02:29:16 INFO - 'cart']}, 02:29:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:29:16 INFO - 'tsvgr_opacity', 02:29:16 INFO - 'tart', 02:29:16 INFO - 'tscrollx', 02:29:16 INFO - 'cart']}, 02:29:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:16 INFO - 'talos_options': ['--disable-e10s'], 02:29:16 INFO - 'tests': ['tp5o']}, 02:29:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:16 INFO - 'tests': ['tp5o']}, 02:29:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:16 INFO - 'talos_options': ['--disable-e10s', 02:29:16 INFO - '--xperf_path', 02:29:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:16 INFO - 'tests': ['tp5n']}, 02:29:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:16 INFO - 'talos_options': ['--xperf_path', 02:29:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:16 INFO - 'tests': ['tp5n']}}, 02:29:16 INFO - 'talos.zip': {'path': '', 02:29:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:29:16 INFO - Running post-action listener: _resource_record_post_action 02:29:16 INFO - ##### 02:29:16 INFO - ##### Running create-virtualenv step. 02:29:16 INFO - ##### 02:29:16 INFO - Running pre-action listener: _resource_record_pre_action 02:29:16 INFO - Running main action method: create_virtualenv 02:29:16 INFO - Creating virtualenv /builds/slave/test/build/venv 02:29:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:29:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:29:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:29:16 INFO - Using real prefix '/tools/python27' 02:29:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:29:16 INFO - Installing distribute.............................................................................................................................................................................................done. 02:29:19 INFO - Installing pip.................done. 02:29:19 INFO - Return code: 0 02:29:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:19 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 0x105746a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105756420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89c8cb0ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e5e00>, '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 0x10544edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055e0b20>, '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.NyAB9RrLb5/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.dbBAjgculM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:29:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:29:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:29:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NyAB9RrLb5/Render', 02:29:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:19 INFO - 'HOME': '/Users/cltbld', 02:29:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:19 INFO - 'LOGNAME': 'cltbld', 02:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:19 INFO - 'MOZ_NO_REMOTE': '1', 02:29:19 INFO - 'NO_EM_RESTART': '1', 02:29:19 INFO - 'PAGER': '/bin/cat', 02:29:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:19 INFO - 'PWD': '/builds/slave/test', 02:29:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:19 INFO - 'SHELL': '/bin/bash', 02:29:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbBAjgculM/Listeners', 02:29:19 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:29:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:19 INFO - 'USER': 'cltbld', 02:29:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:19 INFO - 'XPC_FLAGS': '0x0', 02:29:19 INFO - 'XPC_SERVICE_NAME': '0', 02:29:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:29:20 INFO - Cleaning up... 02:29:20 INFO - Return code: 0 02:29:20 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105746a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105756420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89c8cb0ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e5e00>, '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 0x10544edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055e0b20>, '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.NyAB9RrLb5/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.dbBAjgculM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:29:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NyAB9RrLb5/Render', 02:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:20 INFO - 'HOME': '/Users/cltbld', 02:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:20 INFO - 'LOGNAME': 'cltbld', 02:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:20 INFO - 'MOZ_NO_REMOTE': '1', 02:29:20 INFO - 'NO_EM_RESTART': '1', 02:29:20 INFO - 'PAGER': '/bin/cat', 02:29:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:20 INFO - 'PWD': '/builds/slave/test', 02:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:20 INFO - 'SHELL': '/bin/bash', 02:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbBAjgculM/Listeners', 02:29:20 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:20 INFO - 'USER': 'cltbld', 02:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:20 INFO - 'XPC_FLAGS': '0x0', 02:29:20 INFO - 'XPC_SERVICE_NAME': '0', 02:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:20 INFO - Downloading/unpacking psutil>=0.7.1 02:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:29:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:23 INFO - Installing collected packages: psutil 02:29:23 INFO - Running setup.py install for psutil 02:29:23 INFO - building 'psutil._psutil_osx' extension 02:29:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:29:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:29:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:29:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:29:24 INFO - building 'psutil._psutil_posix' extension 02:29:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:29:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:29:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:29:24 INFO - ^ 02:29:24 INFO - 1 warning generated. 02:29:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:29:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:24 INFO - Successfully installed psutil 02:29:24 INFO - Cleaning up... 02:29:24 INFO - Return code: 0 02:29:24 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105746a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105756420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89c8cb0ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e5e00>, '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 0x10544edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055e0b20>, '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.NyAB9RrLb5/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.dbBAjgculM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:29:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NyAB9RrLb5/Render', 02:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:24 INFO - 'HOME': '/Users/cltbld', 02:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:24 INFO - 'LOGNAME': 'cltbld', 02:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:24 INFO - 'MOZ_NO_REMOTE': '1', 02:29:24 INFO - 'NO_EM_RESTART': '1', 02:29:24 INFO - 'PAGER': '/bin/cat', 02:29:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:24 INFO - 'PWD': '/builds/slave/test', 02:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:24 INFO - 'SHELL': '/bin/bash', 02:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbBAjgculM/Listeners', 02:29:24 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:24 INFO - 'USER': 'cltbld', 02:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:24 INFO - 'XPC_FLAGS': '0x0', 02:29:24 INFO - 'XPC_SERVICE_NAME': '0', 02:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:24 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:27 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:29:27 INFO - Installing collected packages: mozsystemmonitor 02:29:27 INFO - Running setup.py install for mozsystemmonitor 02:29:27 INFO - Successfully installed mozsystemmonitor 02:29:27 INFO - Cleaning up... 02:29:27 INFO - Return code: 0 02:29:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:27 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 0x105746a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105756420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89c8cb0ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e5e00>, '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 0x10544edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055e0b20>, '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.NyAB9RrLb5/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.dbBAjgculM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:29:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:29:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:29:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NyAB9RrLb5/Render', 02:29:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:27 INFO - 'HOME': '/Users/cltbld', 02:29:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:27 INFO - 'LOGNAME': 'cltbld', 02:29:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:27 INFO - 'MOZ_NO_REMOTE': '1', 02:29:27 INFO - 'NO_EM_RESTART': '1', 02:29:27 INFO - 'PAGER': '/bin/cat', 02:29:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:27 INFO - 'PWD': '/builds/slave/test', 02:29:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:27 INFO - 'SHELL': '/bin/bash', 02:29:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbBAjgculM/Listeners', 02:29:27 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:29:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:27 INFO - 'USER': 'cltbld', 02:29:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:27 INFO - 'XPC_FLAGS': '0x0', 02:29:27 INFO - 'XPC_SERVICE_NAME': '0', 02:29:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:27 INFO - Downloading/unpacking blobuploader==1.2.4 02:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:30 INFO - Downloading blobuploader-1.2.4.tar.gz 02:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:29:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:29:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:31 INFO - Downloading docopt-0.6.1.tar.gz 02:29:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:29:31 INFO - Installing collected packages: blobuploader, requests, docopt 02:29:31 INFO - Running setup.py install for blobuploader 02:29:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:29:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:29:31 INFO - Running setup.py install for requests 02:29:32 INFO - Running setup.py install for docopt 02:29:32 INFO - Successfully installed blobuploader requests docopt 02:29:32 INFO - Cleaning up... 02:29:32 INFO - Return code: 0 02:29:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:32 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 0x105746a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105756420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89c8cb0ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e5e00>, '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 0x10544edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055e0b20>, '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.NyAB9RrLb5/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.dbBAjgculM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:29:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:29:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:29:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NyAB9RrLb5/Render', 02:29:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:32 INFO - 'HOME': '/Users/cltbld', 02:29:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:32 INFO - 'LOGNAME': 'cltbld', 02:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:32 INFO - 'MOZ_NO_REMOTE': '1', 02:29:32 INFO - 'NO_EM_RESTART': '1', 02:29:32 INFO - 'PAGER': '/bin/cat', 02:29:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:32 INFO - 'PWD': '/builds/slave/test', 02:29:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:32 INFO - 'SHELL': '/bin/bash', 02:29:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbBAjgculM/Listeners', 02:29:32 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:29:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:32 INFO - 'USER': 'cltbld', 02:29:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:32 INFO - 'XPC_FLAGS': '0x0', 02:29:32 INFO - 'XPC_SERVICE_NAME': '0', 02:29:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:32 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-U0M5h5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:32 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-qodY3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-E6iEyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-fWw183-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-0UjbN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-CGu8i4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-6Jv_yh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-tRGkVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-oNSUdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-oEpyjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-oiuAjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:34 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-14ewX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:34 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Ik5mzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:34 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Rgj_LM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:34 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-0pPCe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:34 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-44bxj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:34 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-i4fufI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:29:34 INFO - Running setup.py install for manifestparser 02:29:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:29:34 INFO - Running setup.py install for mozcrash 02:29:35 INFO - Running setup.py install for mozdebug 02:29:35 INFO - Running setup.py install for mozdevice 02:29:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:29:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:29:35 INFO - Running setup.py install for mozfile 02:29:35 INFO - Running setup.py install for mozhttpd 02:29:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:29:35 INFO - Running setup.py install for mozinfo 02:29:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:29:35 INFO - Running setup.py install for mozInstall 02:29:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:29:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:29:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:29:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:29:35 INFO - Running setup.py install for mozleak 02:29:36 INFO - Running setup.py install for mozlog 02:29:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:29:36 INFO - Running setup.py install for moznetwork 02:29:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:29:36 INFO - Running setup.py install for mozprocess 02:29:36 INFO - Running setup.py install for mozprofile 02:29:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:29:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:29:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:29:36 INFO - Running setup.py install for mozrunner 02:29:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:29:36 INFO - Running setup.py install for mozscreenshot 02:29:37 INFO - Running setup.py install for moztest 02:29:37 INFO - Running setup.py install for mozversion 02:29:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:29:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:29:37 INFO - Cleaning up... 02:29:37 INFO - Return code: 0 02:29:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:37 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 0x105746a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105756420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89c8cb0ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e5e00>, '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 0x10544edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055e0b20>, '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.NyAB9RrLb5/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.dbBAjgculM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:29:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NyAB9RrLb5/Render', 02:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:37 INFO - 'HOME': '/Users/cltbld', 02:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:37 INFO - 'LOGNAME': 'cltbld', 02:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:37 INFO - 'MOZ_NO_REMOTE': '1', 02:29:37 INFO - 'NO_EM_RESTART': '1', 02:29:37 INFO - 'PAGER': '/bin/cat', 02:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:37 INFO - 'PWD': '/builds/slave/test', 02:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:37 INFO - 'SHELL': '/bin/bash', 02:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbBAjgculM/Listeners', 02:29:37 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:37 INFO - 'USER': 'cltbld', 02:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:37 INFO - 'XPC_FLAGS': '0x0', 02:29:37 INFO - 'XPC_SERVICE_NAME': '0', 02:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:37 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-nqACAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:37 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-5X8sRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:37 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-qMmDop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:38 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-3rVkc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:38 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-MX84IS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:38 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-EIaTeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:38 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-EEhH4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:38 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-qOx_Fe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:38 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-h8Awiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:38 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-liI4_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:38 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-qPYckd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:38 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-DKmeqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:39 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Zjc0U_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:39 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-OJC03h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:39 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-AsvnZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:39 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-nJ6Jn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:39 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-I60Ko5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:29:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:42 INFO - Downloading blessings-1.6.tar.gz 02:29:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:29:42 INFO - Installing collected packages: blessings 02:29:42 INFO - Running setup.py install for blessings 02:29:42 INFO - Successfully installed blessings 02:29:42 INFO - Cleaning up... 02:29:42 INFO - Return code: 0 02:29:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:29:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:42 INFO - Reading from file tmpfile_stdout 02:29:42 INFO - Current package versions: 02:29:42 INFO - blessings == 1.6 02:29:42 INFO - blobuploader == 1.2.4 02:29:42 INFO - docopt == 0.6.1 02:29:42 INFO - manifestparser == 1.1 02:29:42 INFO - mozInstall == 1.12 02:29:42 INFO - mozcrash == 0.17 02:29:42 INFO - mozdebug == 0.1 02:29:42 INFO - mozdevice == 0.48 02:29:42 INFO - mozfile == 1.2 02:29:42 INFO - mozhttpd == 0.7 02:29:42 INFO - mozinfo == 0.9 02:29:42 INFO - mozleak == 0.1 02:29:42 INFO - mozlog == 3.1 02:29:42 INFO - moznetwork == 0.27 02:29:42 INFO - mozprocess == 0.22 02:29:42 INFO - mozprofile == 0.28 02:29:42 INFO - mozrunner == 6.11 02:29:42 INFO - mozscreenshot == 0.1 02:29:42 INFO - mozsystemmonitor == 0.0 02:29:42 INFO - moztest == 0.7 02:29:42 INFO - mozversion == 1.4 02:29:42 INFO - psutil == 3.1.1 02:29:42 INFO - requests == 1.2.3 02:29:42 INFO - wsgiref == 0.1.2 02:29:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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')]} 02:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:42 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 0x105746a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105756420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89c8cb0ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e5e00>, '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 0x10544edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055e0b20>, '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.NyAB9RrLb5/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.dbBAjgculM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:29:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NyAB9RrLb5/Render', 02:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:42 INFO - 'HOME': '/Users/cltbld', 02:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:42 INFO - 'LOGNAME': 'cltbld', 02:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:42 INFO - 'MOZ_NO_REMOTE': '1', 02:29:42 INFO - 'NO_EM_RESTART': '1', 02:29:42 INFO - 'PAGER': '/bin/cat', 02:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:42 INFO - 'PWD': '/builds/slave/test', 02:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:42 INFO - 'SHELL': '/bin/bash', 02:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbBAjgculM/Listeners', 02:29:42 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:42 INFO - 'USER': 'cltbld', 02:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:42 INFO - 'XPC_FLAGS': '0x0', 02:29:42 INFO - 'XPC_SERVICE_NAME': '0', 02:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:29:43 INFO - Cleaning up... 02:29:43 INFO - Return code: 0 02:29:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105746a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105756420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89c8cb0ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e5e00>, '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 0x10544edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055e0b20>, '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.NyAB9RrLb5/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.dbBAjgculM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:29:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:29:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NyAB9RrLb5/Render', 02:29:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:43 INFO - 'HOME': '/Users/cltbld', 02:29:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:43 INFO - 'LOGNAME': 'cltbld', 02:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:43 INFO - 'MOZ_NO_REMOTE': '1', 02:29:43 INFO - 'NO_EM_RESTART': '1', 02:29:43 INFO - 'PAGER': '/bin/cat', 02:29:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:43 INFO - 'PWD': '/builds/slave/test', 02:29:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:43 INFO - 'SHELL': '/bin/bash', 02:29:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbBAjgculM/Listeners', 02:29:43 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:29:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:43 INFO - 'USER': 'cltbld', 02:29:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:43 INFO - 'XPC_FLAGS': '0x0', 02:29:43 INFO - 'XPC_SERVICE_NAME': '0', 02:29:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:43 INFO - Downloading/unpacking jsonschema 02:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:29:45 INFO - Downloading/unpacking functools32 (from jsonschema) 02:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:46 INFO - Downloading functools32-3.2.3-2.tar.gz 02:29:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:29:46 INFO - warning: no files found matching '*.txt' 02:29:46 INFO - no previously-included directories found matching 'build' 02:29:46 INFO - no previously-included directories found matching 'dist' 02:29:46 INFO - no previously-included directories found matching '.git*' 02:29:46 INFO - Installing collected packages: jsonschema, functools32 02:29:46 INFO - Running setup.py install for functools32 02:29:46 INFO - warning: no files found matching '*.txt' 02:29:46 INFO - no previously-included directories found matching 'build' 02:29:46 INFO - no previously-included directories found matching 'dist' 02:29:46 INFO - no previously-included directories found matching '.git*' 02:29:46 INFO - Successfully installed jsonschema functools32 02:29:46 INFO - Cleaning up... 02:29:46 INFO - Return code: 0 02:29:46 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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')]} 02:29:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105746a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105756420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89c8cb0ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e5e00>, '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 0x10544edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055e0b20>, '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.NyAB9RrLb5/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.dbBAjgculM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:29:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:29:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:29:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NyAB9RrLb5/Render', 02:29:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:46 INFO - 'HOME': '/Users/cltbld', 02:29:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:46 INFO - 'LOGNAME': 'cltbld', 02:29:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:46 INFO - 'MOZ_NO_REMOTE': '1', 02:29:46 INFO - 'NO_EM_RESTART': '1', 02:29:46 INFO - 'PAGER': '/bin/cat', 02:29:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:46 INFO - 'PWD': '/builds/slave/test', 02:29:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:46 INFO - 'SHELL': '/bin/bash', 02:29:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbBAjgculM/Listeners', 02:29:46 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:29:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:46 INFO - 'USER': 'cltbld', 02:29:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:46 INFO - 'XPC_FLAGS': '0x0', 02:29:46 INFO - 'XPC_SERVICE_NAME': '0', 02:29:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:46 INFO - Downloading/unpacking flake8 02:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:29:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:49 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:29:49 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:29:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:29:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:29:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:29:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:29:50 INFO - no previously-included directories found matching 'docs/_build' 02:29:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:29:50 INFO - Running setup.py install for pep8 02:29:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:29:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:29:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:29:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:29:50 INFO - no previously-included directories found matching 'docs/_build' 02:29:50 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:29:50 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:29:50 INFO - Cleaning up... 02:29:51 INFO - Return code: 0 02:29:51 INFO - Running post-action listener: _resource_record_post_action 02:29:51 INFO - Running post-action listener: _start_resource_monitoring 02:29:51 INFO - Starting resource monitoring. 02:29:51 INFO - ##### 02:29:51 INFO - ##### Running install step. 02:29:51 INFO - ##### 02:29:51 INFO - Running pre-action listener: _resource_record_pre_action 02:29:51 INFO - Running main action method: install 02:29:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:51 INFO - Reading from file tmpfile_stdout 02:29:51 INFO - Detecting whether we're running mozinstall >=1.0... 02:29:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:29:51 INFO - Reading from file tmpfile_stdout 02:29:51 INFO - Output received: 02:29:51 INFO - Usage: mozinstall [options] installer 02:29:51 INFO - Options: 02:29:51 INFO - -h, --help show this help message and exit 02:29:51 INFO - -d DEST, --destination=DEST 02:29:51 INFO - Directory to install application into. [default: 02:29:51 INFO - "/builds/slave/test"] 02:29:51 INFO - --app=APP Application being installed. [default: firefox] 02:29:51 INFO - mkdir: /builds/slave/test/build/application 02:29:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:30:24 INFO - Reading from file tmpfile_stdout 02:30:24 INFO - Output received: 02:30:24 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:30:24 INFO - Running post-action listener: _resource_record_post_action 02:30:24 INFO - ##### 02:30:24 INFO - ##### Running run-tests step. 02:30:24 INFO - ##### 02:30:24 INFO - Running pre-action listener: _resource_record_pre_action 02:30:24 INFO - Running main action method: run_tests 02:30:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:30:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:30:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:30:24 INFO - 2016-05-09 02:30:24.455 screenresolution[1642:7716] starting screenresolution argv=screenresolution get 02:30:24 INFO - 2016-05-09 02:30:24.458 screenresolution[1642:7716] Display 0: 1600x1200x32@60 02:30:24 INFO - 2016-05-09 02:30:24.463 screenresolution[1643:7719] starting screenresolution argv=screenresolution list 02:30:24 INFO - Available Modes on Display 0 02:30:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:30:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:30:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:30:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:30:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:30:24 INFO - Intel Iris: 02:30:24 INFO - Chipset Model: Intel Iris 02:30:24 INFO - Type: GPU 02:30:24 INFO - Bus: Built-In 02:30:24 INFO - VRAM (Dynamic, Max): 1536 MB 02:30:24 INFO - Vendor: Intel (0x8086) 02:30:24 INFO - Device ID: 0x0a2e 02:30:24 INFO - Revision ID: 0x0009 02:30:24 INFO - Displays: 02:30:24 INFO - Display: 02:30:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:30:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:30:24 INFO - Display Serial Number: 1600x1200 60 02:30:24 INFO - Main Display: Yes 02:30:24 INFO - Mirror: Off 02:30:24 INFO - Online: Yes 02:30:24 INFO - Rotation: Supported 02:30:24 INFO - Return code: 0 02:30:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:30:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:30:24 INFO - Python 2.7.3 02:30:24 INFO - Return code: 0 02:30:24 INFO - grabbing minidump binary from tooltool 02:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:24 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 0x1055e5e00>, '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 0x10544edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055e0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:30:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:30:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:30:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:30:24 INFO - Return code: 0 02:30:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:30:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:30:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:30:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:30:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:30:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:30:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:30:25 INFO - Return code: 0 02:30:25 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-0034', '--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 02:30:25 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-0034 --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 02:30:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NyAB9RrLb5/Render', 02:30:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:30:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:30:25 INFO - 'HOME': '/Users/cltbld', 02:30:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:30:25 INFO - 'LOGNAME': 'cltbld', 02:30:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:30:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:25 INFO - 'MOZ_NO_REMOTE': '1', 02:30:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:30:25 INFO - 'NO_EM_RESTART': '1', 02:30:25 INFO - 'PAGER': '/bin/cat', 02:30:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:25 INFO - 'PWD': '/builds/slave/test', 02:30:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:30:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:30:25 INFO - 'SHELL': '/bin/bash', 02:30:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbBAjgculM/Listeners', 02:30:25 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:30:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:30:25 INFO - 'USER': 'cltbld', 02:30:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:30:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:25 INFO - 'XPC_FLAGS': '0x0', 02:30:25 INFO - 'XPC_SERVICE_NAME': '0', 02:30:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:30:25 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-0034', '--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 02:30:26 INFO - mozversion application_buildid: 20160509005049 02:30:26 INFO - mozversion application_changeset: 307b5eca8726f6a72a1d69ba76630a6dbf402477 02:30:26 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:30:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:30:26 INFO - mozversion application_name: Firefox 02:30:26 INFO - mozversion application_remotingname: firefox-dev 02:30:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:30:26 INFO - mozversion application_vendor: Mozilla 02:30:26 INFO - mozversion application_version: 48.0a2 02:30:26 INFO - mozversion platform_buildid: 20160509005049 02:30:26 INFO - mozversion platform_changeset: 307b5eca8726f6a72a1d69ba76630a6dbf402477 02:30:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:30:26 INFO - mozversion platform_version: 48.0a2 02:30:26 INFO - using testdate: 1462786226 02:30:26 INFO - actual date: 1462786226 02:30:26 INFO - starting webserver on 'localhost:49272' 02:30:26 INFO - SUITE-START | Running 2 tests 02:30:26 INFO - TEST-START | dromaeo_css 02:30:26 INFO - operating with platform_type : mac_ 02:30:26 INFO - Initialising browser for dromaeo_css test... 02:30:26 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpgD27tI/profile http://localhost:49272/getInfo.html) 02:30:29 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 02:30:29 INFO - PROCESS | 1661 | colorDepth:24 02:30:29 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 02:30:29 INFO - PROCESS | 1661 | __metrics 02:30:29 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 02:30:29 INFO - TEST-INFO | 1661: exit 0 02:30:29 INFO - Browser initialized. 02:30:29 INFO - Running cycle 1/1 for dromaeo_css test... 02:30:29 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpgD27tI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:30:42 INFO - PROCESS | 1664 | RSS: Main: 221048832 02:30:42 INFO - PROCESS | 1664 | 02:31:31 INFO - PROCESS | 1664 | 1462786291718 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:33:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 02:33:42 INFO - PROCESS | 1664 | RSS: Main: 232574976 02:33:42 INFO - PROCESS | 1664 | 02:36:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 02:36:45 INFO - PROCESS | 1664 | RSS: Main: 243855360 02:36:45 INFO - PROCESS | 1664 | 02:39:46 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 02:39:47 INFO - PROCESS | 1664 | RSS: Main: 245104640 02:39:47 INFO - PROCESS | 1664 | 02:42:49 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 02:42:49 INFO - PROCESS | 1664 | RSS: Main: 247513088 02:42:49 INFO - PROCESS | 1664 | 02:45:52 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 02:45:53 INFO - PROCESS | 1664 | RSS: Main: 249659392 02:45:53 INFO - PROCESS | 1664 | 02:48:55 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 02:48:55 INFO - PROCESS | 1664 | RSS: Main: 250519552 02:48:55 INFO - PROCESS | 1664 | 02:48:55 INFO - PROCESS | 1664 | __start_tp_report 02:48:55 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 02:48:55 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 02:48:55 INFO - PROCESS | 1664 | |i|pagename|runs| 02:48:55 INFO - PROCESS | 1664 | |0;dojo.html;2733.398652296494;2913.9854300728493;3000.9849950750245;3013.1412547423984;3047.878084876605;4841.467438581758;4968.354113965189;4980.352554167957;4990.700557966516;5010.799568017275;3649.1424515238896;4052.49343832021;4328.588784065517;4893.853184404465;4978.900421991558;3641.9635803642022;3648.890533284006;4581.060882519084;4708.693934894238;4719.693219940693;4419.425474688286;4677.672562920597;4763.452202996665;4801.519848015206;4825.372701548794;4539.7049191802425;4598.609118224955;4622.237330840408;4669.6731228814;4688.2264426369775;4557.380477046314;4585.128825523157;4591.403117594725;4628.861134165981;4689.929651055203;3510.648935106469;4302.505211900609;4501.392312037866;4545.295479200696;4619.214733495314;4505.3917721107555;4551.453825540923;4598.632109431237;4680.180968330528;4724.7401392923375;5350.759215835296;5370.731463426852;5391.523758707882;5436.891262174734;5470.261514695505;4591.449026116854;5238.899831035487;5534.4742249486235;5547.472990065938;5573.749181286852;5072.5688316493515;5110.463401342842;5115.7442127893455;5118.360204974379;5128.512791284822;5604.215409842625;5607.887842243133;5621.15682647598;5634.915476267859;5683.8010669626365;5181.98951204519;5316.973415132977;5340.652857564246;5359;5389.056915039852;5477.835664930059;5479.479449452296;5492.093804522289;5502.862428439321;5523.392426833045;5381.515663590296;5398.433164517748;5411.350637923396;5421.626885545659;5427.213054107211;5140.434552199255;5257.421683614799;5339.786408543692;5372.973135134377;5422.945770542323;4260.2970509866145;4337.436133302651;4350.0212452198;4419.624331931757;4464.888377790581;2866.813657112281;3211.518272259133;3928.175083232496;3951.644352008333;3969.0275882408996;4424.778761061934;4664.836730714476;4726.574608285271;4842.467328593852;4859.611231101503;5329.387120481194;5382.165764306539;5395.62230643859;5422.972885135628;5452.209429632681;3882.825272862728;4974.90050198994;4989.0520801048415;5025.924611130836;5198.92201616976;3906.921861562753;4520.4801447833925;4702.600278976257;4781.497942716034;4785.904281914342;5724.313082430135;5738.426157384228;5763.740631671585;5800.013997620415;5816.27296587918;5466.262054622575;5483;5503.862403439946;5518.034343989705;5528.7235638218735;5621;5629.915551266652;5705.743241554057;5750.396208398058;5780.884382312246;5341.599380046589;5371.5702743781185;5375.973120134374;5381.3004309441085;5390.137577987503;4175.791210439526;5464.371597266365;5475.972620136874;5485.149801780731;5505.504504594445;5115.079285728604;5180.7409629519125;5204.973975130251;5220.929709409486;5231.346081739783;5208.401033881152;5247.055530004636;5248.160294352885;5261.289725886956;5264.473552644705;4890.777305673581;5022.723750193774;5085.415177254663;5119.488051195;5130.7178105204575;4046.59534046593;5234.895302094013;5251.89496210066;5287.3919499256535;5331.920021199608;5150.381954165524;5173.534381905646;5200.193969934666;5226.372835259793;5226.817061402832 02:48:55 INFO - PROCESS | 1664 | |1;ext.html;440.9801558929848;464.32904453065316;482.4861522478561;502.6833095150055;518.5048278893657;36623.26753464933;37867;37969.810150949204;38076.238475230515;38108;40961;41873.58126418735;41881;41998.79000605;42042;7833.9216607833905;9309.069093090671;9356.906430935705;9394.201492873113;9402.247820174369;9345.299102567335;9354.719358419225;9456.479893605849;9473.242140628767;9478.90521094787;8018.358531317481;9449.622015119421;9471.194948429391;9495.240380769557;9526.42841429513;9417.717468475958;9432.19826314764;9475.857862132005;9485.33602647815;9532.95233523835;9298.11667891556;9331.440113593138;9434.858477122849;9448.952755236249;9459.432434053979;37864.48542058314;37971.430428543594;38421;38455;38493.42259866104;2572.794176465878;2925.297928497145;2988.8505574721175;3116.9532457013165;3190.441672707267;24586.770661467035;24752;25174.748252517424;25405.74594254052;25939;2529.45703121096;2589.7280785517437;2598.701149367828;2625.225558460259;2625.973740262592;360031;362537;363435;366296;367870;33079;33096.834515827264;33300.1674958128;33431.164220894185;34307.828460857534;33344.83327583395;33944.30278486066;33976.49035264473;34224.486632700035;34481.482777758356;14651.633709157357;14970.17664028489;15156.696866062837;15171.013884097498;15181.316840742193;10903.727406814893;12499.875001250053;12836.101472896808;12864.292463914575;12870.034747393984;7876.881846772303;7941.960290198512;7970.242826931432;8004.759857204293;8006.039275286887;2833.688294287627;3372.696457318804;3375.9907364890532;3377.4089534331392;3401.2347221875375;808.7412028151053;814.3688641303038;856.1737922904302;861.5390765940317;864.7232885476587;26212.60681089785;26635.934562617666;26860.865695671786;26981;27149.864250679017;915.4370062411703;919.2416256588341;938.7465384346189;991.2317953586029;1022.1873610479687;13698.452061917611;13701.314934253247;13707.520236791665;13951;14005.5098071567;631227;632070;635432;637279;641777;57930.42069579334;58487.70756146192;58552.41447585555;58725;59051.704741476016;1216.9634910952686;1404.676924307415;1421.1899217445957;1435.3038776193537;1452.3391856705173;25660.743392565837;26389;26554.203373898814;26997.73002269952;27217.183484495497;43847.56152438435;47041.764791175825;49983;50519.74740126423;50542.241866371296;491644;494835;498665;499220;501038;21709.457263567907;25226.36934076663;25506.852191651793;26275.817588208196;26391.20826375212;32246.032619021466;44000.11999760051;44485.33272000987;44934.55065449304;45672.77163614161;27948.72051279461;34812.08533530579;36669.63330366662;37946;38269.42595861009;35826.641733583376;36365.81817091003;36529;37001.44497832588;37070;33741.83129084437;34003.65996340005;36254.27491450103;37303.25393492169;37866.242675146896;29642.555361670024;30883.0735077948;31301.530477042408;31771.046868593978;31998.840005799822;23920.88039559791;23941.281761547183;24077.398065048514;24120;24305 02:48:55 INFO - PROCESS | 1664 | |2;jquery.html;368.4565266232308;370.353732736375;370.486875677187;381.1405281091139;385.1661153602451;19154.13806378713;19879.602407951832;20052.899735501338;20989.37031889044;21024.684629730567;20428.89785551074;20555.897220513878;20639.071241794118;20746.89626551865;20753.896230518865;5603.047481928072;5968.79109231177;5979.133025711291;5979.5515336349945;5995.340512543617;5580.972095139518;5824.650520968734;5884.794032208873;5918.1418694289305;5934.1098835174635;5838;5873.0603103503445;5887.705614719247;5897.911531327033;5911.379305172959;5564.137558678391;5747.109198074305;5823.417658234185;5867.061270196769;5869.002269614176;5106.387233531963;5107.770150343244;5115;5115.309433226504;5143.948560514385;25089.12188073409;25361.36596585082;25672.229833105037;25673.35816604581;25709.22872313815;1782.4652604218682;2078.511549785799;2441.6825812644242;2526.027479420418;2885.581590669341;14363.281835908267;14367.137971721626;14530;14875.404983800636;15124.697506049928;1691.1121661127884;1760.4542591796585;1772.8758986870923;1776.7068433708423;1782.3850771483817;95494;96989.06021879593;98745;99569.5064574032;99781;10553;10651.254412191072;11034;11313;11487.310761354345;13831.30843457823;13855.515056972958;13929.024968252328;13943.860561394458;13952.720945581235;7377.372923301579;8686.695965641173;8813.691520796741;8922.241609463139;8983.55082245885;8182.427230093836;8337.041240257327;9073.13805188506;9088.363814532919;9199.770005749777;6735.191776986793;6807.727690892408;6820.249772525019;6839.863202735917;6842.863142737117;1817.5183576352276;1873.5597134673342;1897.6204759048246;1901.6767149584605;1923.710784166837;690.944724422045;780.2665494435327;812.5530957973091;883.0948278014957;902.2691619787993;15249.923750381176;15762.605934851721;18874.245030198912;19694.507637308896;19825.20699172017;851.748734123435;862.2799962031773;892.2371372476568;892.4377642085575;903.6204793986542;10263.794724105626;10465.319754216103;10485;10537.894621053845;10550.525226364833;535456;539488;541337;550700;551177;61307.6934615333;61673;78582.42835143379;78869.81695274575;101121;983.5672304186136;993.1062044160313;995.1889210293732;999.4802702594756;1003.1673710820002;15129.417980451639;17040.914795425942;17183.31266749316;17302.5674358137;17383.652326953135;44331;44744.77627611841;45055;45373;46166.07667846692;58622;62369.45950255167;62413.375866240756;62755;62903;7877.606119694107;8054.476459030145;8074.475159114639;8138.470999385021;8215.71624064803;12307;12602.180858244184;12662.556810511956;12904.41930113166;12945.935270323587;9845.818501779831;10018.849717254392;10165.898341016495;10191.84712229332;10447.268691191848;9858.112769850748;10506;10608.89391106079;10693.411862347335;10905.83641245366;9957.850632240377;10822.783544329226;11029.338239705643;11093.944530277297;11345;6269.902767015845;7899.723509677134;8009.639566219417;8243.587820608825;8297.543635100128;9058.320625952949;10310.536025878704;10329.741756455847;10405.757218796176;10459.110975567162 02:48:55 INFO - PROCESS | 1664 | |3;mootools.html;1432.4055516960461;1452.0489079652825;1607.7025750236207;1741.5210817025315;1802.22504323141;7672.539647621133;8238.50568965861;8414.705485308015;8647.956760216206;8676.47941123533;8257.25684688378;8298.336133109351;8322.75031749047;8370.832583348329;8373.874391884126;2340.7659234076627;2353.7175538935353;2370.5258948210426;2374.0859768988976;2408.2414039577566;2310.919117830876;2336.100601268507;2358.422186564294;2360.6931098957198;2373.1456675596773;2320.640300753386;2326.6510023496444;2375.740857345601;2384.7138343398815;2391.712994440661;2342.7891489766002;2360.1385494294545;2378.512404956988;2378.7502312257225;2385.962106483668;2053.661145910923;2300.620397634388;2380.6310021946624;2385.105585405473;2395.1856368834506;8461.83076338476;8464.746057618282;8497.787555311104;8498.660053597849;8518.829623407559;5129.435762066169;5322.33470816148;5338.62629615927;5350.973245133789;5366.8926621467745;3422.691957723817;3431.159365955357;3433.759636825423;3489.0928358626684;3490.6683865032783;981.4749109226601;989.648674720471;1004.2167109654408;1013.5185786751308;1027.2347101409498;11334.036606888425;11342.376169310684;11424.600138995178;11513.791051939594;11586.826197607043;4669.9259170391;4724.291356296583;4789.832355867528;4809.879753006203;4868;8389.161083891684;9926.602935882629;9955.80088398228;10008.899911000797;10139.695809125738;5139.794408223704;5283.181106928432;5304.628675992721;5376.892462150735;5379.273798037293;3686.705063594906;3961.4057891316534;4216.873493795191;4668.439787225487;4827.179379505493;3960.128771670228;4003;4025.355943049098;4103.2409004334295;4106.733062350938;1162.6918866500384;1315.9407826647823;1320.3002408723391;1328.6080606221187;1331.7003674173234;795.411395567276;797.6849144587856;798.1260519730886;806.0005593064558;819.1153554161491;5418.83743487696;5439.755211015514;5548.167774833807;5602.075657516464;5711.3146422428745;873.3537182485043;887.9644814207488;895.0154829687292;897.6185121323412;909.9590518426687;3060.5562193482265;3630.9636903631153;3677.687396571321;3679.35611268027;3692.3169213695014;10121.69634910954;10456.738581535523;10458.111060559786;10475.214358922958;10491.003354681294;8556.443831150955;10324.587016519405;10370.22223333253;10404.739881502872;10429.895701043044;8070.717524886601;8092.271695547429;8128.59357032146;8184.345252379795;8191.836163276701;4784.114938736345;5013.373328333958;5069.493050695049;5078.060558796635;5079.528470895712;9065.320100992583;9182.724518264462;9319.813603728024;9371.906280937103;9490.098440648128;9428.764280893032;9532.189763869872;9624.422534647663;9654.227661787181;9658.75853103678;6577.3422657733845;7297.270272972873;7421.220771818881;7680.5391676499585;7921.683132674629;7902.2492863180205;7921.405894558046;7944.324732397811;7963.323117535075;8023.558704271321;6935.202451718117;7051.929480705127;7147.392471639969;7182.569045857265;7235.855282894207;7059.682314295766;7075.6815943281645;7170.928290717026;7248.96375518119;7264.963675181591;6420.743170273324;6555.246146693191;6628;6630.30381809903;6670.999350097524;4390.626796722315;4442.200403927324;4444.28891377378;4515.68390212682;4605.40129783126;4526.049529598821;4537;4601.585857272861;4613.169629466594;4622.861314160446 02:48:55 INFO - PROCESS | 1664 | |4;prototype.html;291.09179360395564;293.1571731272591;295.3089769938344;297.4140942343583;300.35273984563275;5435.293411856458;5459.5905307101975;5468.808591699286;5527.695976721278;5532.529734972522;5461.098918678424;5462.562994960404;5484.040292948728;5485.259489968853;5502.202180683798;3882.8058597070103;3942.408638704203;4103.507579090513;4119.485064366954;4120.917581648365;4069.633732964033;4096.32410652242;4101.077257617042;4114.341705327149;4116.485439320085;4027.2549578327958;4031.0728532437556;4038.293298672735;4039;4062.329715596923;4042.8787136385954;4062.1875624875142;4064.3700226464944;4110.116324990141;4119.876403707894;3594.101474631342;3597.730170237243;3601.207734298476;3613.6747692707518;3613.7470377073614;3985.864028751821;4315.5900189481945;5018.272350509155;5057.115004874132;5071.492850714899;4587.220172570671;4620.9768951155365;4839.37088178538;4842.44311904132;4852.8544143675745;4632.583067523905;5849.970750146222;5881.529477641779;5918.082697181943;5949.613275137102;3330.350581636582;4093.140440507497;4094.324436467979;4107.281225785506;4121.175764847043;4943.950560494385;6163.845903852395;6277.560570760049;6285.43431091204;6306.5900716452825;4144.3990621360335;4146.150039241948;4150.481189851269;4167.97916010418;4172.520160181557;3454.6545345465756;4235.682323825726;4247.384129301296;4248.4901811782165;4254.765987870697;4584.174848527297;4821.63837712173;4835.709857408567;4925.655204135748;4935;4856.344393506902;5919.023361145449;5931.6441013539325;5949.3753155918;5968.9701551492835;1425.8788003019858;1467.4350375105662;1468.0604413175577;1472.4551915791012;1477.3573495529265;948.1892981500855;953.666216824106;975.2100798353357;985.1773768902902;985.8225519406576;5182.870428239325;5208;5230.607704422184;5244.265802787613;5256.710880901587;6065.8180254592435;6088.878222435526;6119.112728654321;6119.938800611937;6152.415520525543;4962.255661650781;5190.039842629126;5213.556847667915;5218.008578370098;5226.45122262157;3118.7972781769113;3244.026791962402;3263.7552183586326;3278.8032718036993;3294.4893541501106;5516.696581688046;6272.964960781511;6514.283428822826;6518.967405162944;6594.505412094112;6420.101185833987;6424.582402143846;6436.3241859605005;6460.580062295936;6489.5781774184525;6482.189726284215;6529.836754081091;6547.508936829757;6547.934520654828;6549.148610680581;4939.777710003131;5769.336526299529;6126.724297406717;6185.134081228542;6226.750929962932;6131.995322292134;6170.290416602148;6185.783497577563;6214.782482613087;6217.813465596039;6526.216853977553;6531.444827189742;6536.803895882941;6551.344865513411;6560.343965603402;3914.8042597870563;3966.9404958926216;3968.682505399619;3980.641742243212;3985.362342025262;4847.684900481458;6103.572749907464;6131.386861313833;6136.631802091889;6154.569180157346;5583.972080139573;5886.146508756292;5902.5277977762535;5920.052791553331;5968.522518198447;5873.911891321548;5888.205092312483;5892.499137573354;5894.086416605433;5909.645421274738;4581.785826755979;4875.731834748981;5389.434109418455;5833.154192642128;5929.962256605163;4651.139539185261;4709.976450117728;5895.970520147372;5938.228030356199;5958.493528050164;4598.402207712976;5424.7558859850615;5753.8561535961935;5775.8556036099435;5799.913001305068 02:48:55 INFO - PROCESS | 1664 | |5;yui.html;647.9125318082057;670.2191946382463;704.5596502186137;712.8895021271703;728.0862517540486;14548;14586.197759123244;14668.853311466883;14773.630659233524;14791.186484743335;10883.455827208627;13473.258970756604;13503.93248033761;13505.324733763322;13530.594082177526;696.4915611603533;867.154524338771;874.1433395272621;890.4301247201796;893.5532233883058;883.4213590098464;892.9330300227476;894.575076838503;896.6592694775976;897.9583682927805;947.7062110745657;948.3598570964606;948.4214629076258;950.5484894675043;955.0926619711267;941.0260380506178;944.8252073366415;946.4179529589322;946.5787724462617;949.0462085603992;698.7728988078894;705.1186017478152;705.8023753349071;707.9504434689574;707.9575225486436;14676.853231467656;14699.044562103536;14731.04248223862;14734.557963261119;14777.039492432958;9872;12045.93977030118;12134.635960921187;12256.0807939404;12267.754644907141;9549.331546791727;9660.565274562661;9717.076877696607;9814.263930205192;9820.67036315496;3306.7354611631013;3394.4908263760385;3405.5743032120986;3452.8446219920165;3455.1189446691183;64625.67687161581;69005.3099469004;76726.23273767247;77668.61165694192;78126.82809757857;8903.73288801337;9201.631934722536;9203.677871274473;9302.348835581444;9378.718638440858;21148.259810906544;21283.574328513343;21352.039158237916;21381.78618213829;21629;7004.894926576106;7097.822554436078;7108.92891071093;7278.854422911512;7333.74331898381;3785.981070094687;3834.175652234783;3868;3869.3228684980018;3904.1996390739832;3223.62928263248;3457.567804024497;3478.9652103479148;3517.0152357339985;3517.3317069756663;644.3588629313863;644.3878315600199;644.9806505804834;646.689588997284;647.0811447744214;509.73239049499307;530.1729302288443;531.7235037780332;538.2894579155477;559.2031355318736;7247.383972362408;7530.397568194531;7537.133229678548;7746.30283274508;7749.690012399554;526.476156224559;526.5576915391014;527.7227380311177;529.6689569019363;544.4827413956677;3453.7755045921936;3541.4333706606817;3544.680978711928;3570.785752854785;3708.2768860072565;83850.322993541;92945;93304;94674.52662736642;94810.0518994815;55221.44778552243;71470;71982.64008679923;72725.6363718178;77467.61266193746;66323.00515492175;66491.66754166198;67045;67402.32597674058;67635.64728705496;6143.508519318533;7774.805629859299;8056.274935255855;8098.392620553364;8106.83786324282;34044.829775851955;45732.542674572825;46357.76821115873;46779.29831052605;47681.761591191826;62798.37201628108;65408.345916540224;66092.33907660862;66421.66789166024;66457.66771166306;10385.688429347132;10396.517166748921;10585.153187745116;10707.732306692395;10776.622818201638;11282.323060616389;11369.488373023069;11553.653390397965;11592.88407115918;12073.154879158727;10252.574536484377;10502.159827213653;10619.178735797757;10785.298955567863;10795.730106747395;10690.305130166515;10771.192160588143;10849.728756781144;10859.891401086204;11173.832392513956;10326.535305911402;10736.248462607542;10966.780664386828;10980.286281391684;10987.175961802737;9080.727578172666;9298.442093474414;9332.673356432493;9335.439873607605;9486.905130948879;8469.957650211709;8713.607887644945;8753.47479151253;9202.016525134293;9211.815763684555 02:48:55 INFO - PROCESS | 1664 | __end_tp_report 02:48:55 INFO - PROCESS | 1664 | __start_cc_report 02:48:55 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,434 02:48:55 INFO - PROCESS | 1664 | __end_cc_report 02:48:55 INFO - PROCESS | 1664 | __startTimestamp1462787335789__endTimestamp 02:48:55 INFO - PROCESS | 1664 | 02:48:55 INFO - PROCESS | 1664 | ------- Summary: start ------- 02:48:55 INFO - PROCESS | 1664 | Number of tests: 6 02:48:55 INFO - PROCESS | 1664 | 02:48:55 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:4940.19 Median:5130.72 stddev:650.64 (12.7%) stddev-sans-first:629.18 02:48:55 INFO - PROCESS | 1664 | Values: 2733.4 2914.0 3001.0 3013.1 3047.9 4841.5 4968.4 4980.4 4990.7 5010.8 3649.1 4052.5 4328.6 4893.9 4978.9 3642.0 3648.9 4581.1 4708.7 4719.7 4419.4 4677.7 4763.5 4801.5 4825.4 4539.7 4598.6 4622.2 4669.7 4688.2 4557.4 4585.1 4591.4 4628.9 4689.9 3510.6 4302.5 4501.4 4545.3 4619.2 4505.4 4551.5 4598.6 4680.2 4724.7 5350.8 5370.7 5391.5 5436.9 5470.3 4591.4 5238.9 5534.5 5547.5 5573.7 5072.6 5110.5 5115.7 5118.4 5128.5 5604.2 5607.9 5621.2 5634.9 5683.8 5182.0 5317.0 5340.7 5359.0 5389.1 5477.8 5479.5 5492.1 5502.9 5523.4 5381.5 5398.4 5411.4 5421.6 5427.2 5140.4 5257.4 5339.8 5373.0 5422.9 4260.3 4337.4 4350.0 4419.6 4464.9 2866.8 3211.5 3928.2 3951.6 3969.0 4424.8 4664.8 4726.6 4842.5 4859.6 5329.4 5382.2 5395.6 5423.0 5452.2 3882.8 4974.9 4989.1 5025.9 5198.9 3906.9 4520.5 4702.6 4781.5 4785.9 5724.3 5738.4 5763.7 5800.0 5816.3 5466.3 5483.0 5503.9 5518.0 5528.7 5621.0 5629.9 5705.7 5750.4 5780.9 5341.6 5371.6 5376.0 5381.3 5390.1 4175.8 5464.4 5476.0 5485.1 5505.5 5115.1 5180.7 5205.0 5220.9 5231.3 5208.4 5247.1 5248.2 5261.3 5264.5 4890.8 5022.7 5085.4 5119.5 5130.7 4046.6 5234.9 5251.9 5287.4 5331.9 5150.4 5173.5 5200.2 5226.4 5226.8 02:48:55 INFO - PROCESS | 1664 | 02:48:55 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:61263.74 Median:202269.82 stddev:137022.98 (67.7%) stddev-sans-first:137331.27 02:48:55 INFO - PROCESS | 1664 | Values: 441.0 464.3 482.5 502.7 518.5 36623.3 37867.0 37969.8 38076.2 38108.0 40961.0 41873.6 41881.0 41998.8 42042.0 7833.9 9309.1 9356.9 9394.2 9402.2 9345.3 9354.7 9456.5 9473.2 9478.9 8018.4 9449.6 9471.2 9495.2 9526.4 9417.7 9432.2 9475.9 9485.3 9533.0 9298.1 9331.4 9434.9 9449.0 9459.4 37864.5 37971.4 38421.0 38455.0 38493.4 2572.8 2925.3 2988.9 3117.0 3190.4 24586.8 24752.0 25174.7 25405.7 25939.0 2529.5 2589.7 2598.7 2625.2 2626.0 360031.0 362537.0 363435.0 366296.0 367870.0 33079.0 33096.8 33300.2 33431.2 34307.8 33344.8 33944.3 33976.5 34224.5 34481.5 14651.6 14970.2 15156.7 15171.0 15181.3 10903.7 12499.9 12836.1 12864.3 12870.0 7876.9 7942.0 7970.2 8004.8 8006.0 2833.7 3372.7 3376.0 3377.4 3401.2 808.7 814.4 856.2 861.5 864.7 26212.6 26635.9 26860.9 26981.0 27149.9 915.4 919.2 938.7 991.2 1022.2 13698.5 13701.3 13707.5 13951.0 14005.5 631227.0 632070.0 635432.0 637279.0 641777.0 57930.4 58487.7 58552.4 58725.0 59051.7 1217.0 1404.7 1421.2 1435.3 1452.3 25660.7 26389.0 26554.2 26997.7 27217.2 43847.6 47041.8 49983.0 50519.7 50542.2 491644.0 494835.0 498665.0 499220.0 501038.0 21709.5 25226.4 25506.9 26275.8 26391.2 32246.0 44000.1 44485.3 44934.6 45672.8 27948.7 34812.1 36669.6 37946.0 38269.4 35826.6 36365.8 36529.0 37001.4 37070.0 33741.8 34003.7 36254.3 37303.3 37866.2 29642.6 30883.1 31301.5 31771.0 31998.8 23920.9 23941.3 24077.4 24120.0 24305.0 02:48:55 INFO - PROCESS | 1664 | 02:48:55 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:30774.17 Median:45214.00 stddev:89510.46 (198.0%) stddev-sans-first:89732.44 02:48:55 INFO - PROCESS | 1664 | Values: 368.5 370.4 370.5 381.1 385.2 19154.1 19879.6 20052.9 20989.4 21024.7 20428.9 20555.9 20639.1 20746.9 20753.9 5603.0 5968.8 5979.1 5979.6 5995.3 5581.0 5824.7 5884.8 5918.1 5934.1 5838.0 5873.1 5887.7 5897.9 5911.4 5564.1 5747.1 5823.4 5867.1 5869.0 5106.4 5107.8 5115.0 5115.3 5143.9 25089.1 25361.4 25672.2 25673.4 25709.2 1782.5 2078.5 2441.7 2526.0 2885.6 14363.3 14367.1 14530.0 14875.4 15124.7 1691.1 1760.5 1772.9 1776.7 1782.4 95494.0 96989.1 98745.0 99569.5 99781.0 10553.0 10651.3 11034.0 11313.0 11487.3 13831.3 13855.5 13929.0 13943.9 13952.7 7377.4 8686.7 8813.7 8922.2 8983.6 8182.4 8337.0 9073.1 9088.4 9199.8 6735.2 6807.7 6820.2 6839.9 6842.9 1817.5 1873.6 1897.6 1901.7 1923.7 690.9 780.3 812.6 883.1 902.3 15249.9 15762.6 18874.2 19694.5 19825.2 851.7 862.3 892.2 892.4 903.6 10263.8 10465.3 10485.0 10537.9 10550.5 535456.0 539488.0 541337.0 550700.0 551177.0 61307.7 61673.0 78582.4 78869.8 101121.0 983.6 993.1 995.2 999.5 1003.2 15129.4 17040.9 17183.3 17302.6 17383.7 44331.0 44744.8 45055.0 45373.0 46166.1 58622.0 62369.5 62413.4 62755.0 62903.0 7877.6 8054.5 8074.5 8138.5 8215.7 12307.0 12602.2 12662.6 12904.4 12945.9 9845.8 10018.8 10165.9 10191.8 10447.3 9858.1 10506.0 10608.9 10693.4 10905.8 9957.9 10822.8 11029.3 11093.9 11345.0 6269.9 7899.7 8009.6 8243.6 8297.5 9058.3 10310.5 10329.7 10405.8 10459.1 02:48:55 INFO - PROCESS | 1664 | 02:48:55 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5486.43 Median:5041.43 stddev:3073.66 (61.0%) stddev-sans-first:3067.18 02:48:55 INFO - PROCESS | 1664 | Values: 1432.4 1452.0 1607.7 1741.5 1802.2 7672.5 8238.5 8414.7 8648.0 8676.5 8257.3 8298.3 8322.8 8370.8 8373.9 2340.8 2353.7 2370.5 2374.1 2408.2 2310.9 2336.1 2358.4 2360.7 2373.1 2320.6 2326.7 2375.7 2384.7 2391.7 2342.8 2360.1 2378.5 2378.8 2386.0 2053.7 2300.6 2380.6 2385.1 2395.2 8461.8 8464.7 8497.8 8498.7 8518.8 5129.4 5322.3 5338.6 5351.0 5366.9 3422.7 3431.2 3433.8 3489.1 3490.7 981.5 989.6 1004.2 1013.5 1027.2 11334.0 11342.4 11424.6 11513.8 11586.8 4669.9 4724.3 4789.8 4809.9 4868.0 8389.2 9926.6 9955.8 10008.9 10139.7 5139.8 5283.2 5304.6 5376.9 5379.3 3686.7 3961.4 4216.9 4668.4 4827.2 3960.1 4003.0 4025.4 4103.2 4106.7 1162.7 1315.9 1320.3 1328.6 1331.7 795.4 797.7 798.1 806.0 819.1 5418.8 5439.8 5548.2 5602.1 5711.3 873.4 888.0 895.0 897.6 910.0 3060.6 3631.0 3677.7 3679.4 3692.3 10121.7 10456.7 10458.1 10475.2 10491.0 8556.4 10324.6 10370.2 10404.7 10429.9 8070.7 8092.3 8128.6 8184.3 8191.8 4784.1 5013.4 5069.5 5078.1 5079.5 9065.3 9182.7 9319.8 9371.9 9490.1 9428.8 9532.2 9624.4 9654.2 9658.8 6577.3 7297.3 7421.2 7680.5 7921.7 7902.2 7921.4 7944.3 7963.3 8023.6 6935.2 7051.9 7147.4 7182.6 7235.9 7059.7 7075.7 7170.9 7249.0 7265.0 6420.7 6555.2 6628.0 6630.3 6671.0 4390.6 4442.2 4444.3 4515.7 4605.4 4526.0 4537.0 4601.6 4613.2 4622.9 02:48:55 INFO - PROCESS | 1664 | 02:48:55 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4798.46 Median:5222.23 stddev:1510.82 (28.9%) stddev-sans-first:1476.69 02:48:55 INFO - PROCESS | 1664 | Values: 291.1 293.2 295.3 297.4 300.4 5435.3 5459.6 5468.8 5527.7 5532.5 5461.1 5462.6 5484.0 5485.3 5502.2 3882.8 3942.4 4103.5 4119.5 4120.9 4069.6 4096.3 4101.1 4114.3 4116.5 4027.3 4031.1 4038.3 4039.0 4062.3 4042.9 4062.2 4064.4 4110.1 4119.9 3594.1 3597.7 3601.2 3613.7 3613.7 3985.9 4315.6 5018.3 5057.1 5071.5 4587.2 4621.0 4839.4 4842.4 4852.9 4632.6 5850.0 5881.5 5918.1 5949.6 3330.4 4093.1 4094.3 4107.3 4121.2 4944.0 6163.8 6277.6 6285.4 6306.6 4144.4 4146.2 4150.5 4168.0 4172.5 3454.7 4235.7 4247.4 4248.5 4254.8 4584.2 4821.6 4835.7 4925.7 4935.0 4856.3 5919.0 5931.6 5949.4 5969.0 1425.9 1467.4 1468.1 1472.5 1477.4 948.2 953.7 975.2 985.2 985.8 5182.9 5208.0 5230.6 5244.3 5256.7 6065.8 6088.9 6119.1 6119.9 6152.4 4962.3 5190.0 5213.6 5218.0 5226.5 3118.8 3244.0 3263.8 3278.8 3294.5 5516.7 6273.0 6514.3 6519.0 6594.5 6420.1 6424.6 6436.3 6460.6 6489.6 6482.2 6529.8 6547.5 6547.9 6549.1 4939.8 5769.3 6126.7 6185.1 6226.8 6132.0 6170.3 6185.8 6214.8 6217.8 6526.2 6531.4 6536.8 6551.3 6560.3 3914.8 3966.9 3968.7 3980.6 3985.4 4847.7 6103.6 6131.4 6136.6 6154.6 5584.0 5886.1 5902.5 5920.1 5968.5 5873.9 5888.2 5892.5 5894.1 5909.6 4581.8 4875.7 5389.4 5833.2 5930.0 4651.1 4710.0 5896.0 5938.2 5958.5 4598.4 5424.8 5753.9 5775.9 5799.9 02:48:55 INFO - PROCESS | 1664 | 02:48:55 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:17271.10 Median:32636.18 stddev:24302.88 (74.5%) stddev-sans-first:24339.00 02:48:55 INFO - PROCESS | 1664 | Values: 647.9 670.2 704.6 712.9 728.1 14548.0 14586.2 14668.9 14773.6 14791.2 10883.5 13473.3 13503.9 13505.3 13530.6 696.5 867.2 874.1 890.4 893.6 883.4 892.9 894.6 896.7 898.0 947.7 948.4 948.4 950.5 955.1 941.0 944.8 946.4 946.6 949.0 698.8 705.1 705.8 708.0 708.0 14676.9 14699.0 14731.0 14734.6 14777.0 9872.0 12045.9 12134.6 12256.1 12267.8 9549.3 9660.6 9717.1 9814.3 9820.7 3306.7 3394.5 3405.6 3452.8 3455.1 64625.7 69005.3 76726.2 77668.6 78126.8 8903.7 9201.6 9203.7 9302.3 9378.7 21148.3 21283.6 21352.0 21381.8 21629.0 7004.9 7097.8 7108.9 7278.9 7333.7 3786.0 3834.2 3868.0 3869.3 3904.2 3223.6 3457.6 3479.0 3517.0 3517.3 644.4 644.4 645.0 646.7 647.1 509.7 530.2 531.7 538.3 559.2 7247.4 7530.4 7537.1 7746.3 7749.7 526.5 526.6 527.7 529.7 544.5 3453.8 3541.4 3544.7 3570.8 3708.3 83850.3 92945.0 93304.0 94674.5 94810.1 55221.4 71470.0 71982.6 72725.6 77467.6 66323.0 66491.7 67045.0 67402.3 67635.6 6143.5 7774.8 8056.3 8098.4 8106.8 34044.8 45732.5 46357.8 46779.3 47681.8 62798.4 65408.3 66092.3 66421.7 66457.7 10385.7 10396.5 10585.2 10707.7 10776.6 11282.3 11369.5 11553.7 11592.9 12073.2 10252.6 10502.2 10619.2 10785.3 10795.7 10690.3 10771.2 10849.7 10859.9 11173.8 10326.5 10736.2 10966.8 10980.3 10987.2 9080.7 9298.4 9332.7 9335.4 9486.9 8470.0 8713.6 8753.5 9202.0 9211.8 02:48:55 INFO - PROCESS | 1664 | -------- Summary: end -------- 02:48:55 INFO - PROCESS | 1664 | 02:48:56 INFO - TEST-INFO | 1664: exit 0 02:48:56 INFO - TEST-OK | dromaeo_css | took 1110172ms 02:48:56 INFO - TEST-START | kraken 02:48:56 INFO - operating with platform_type : mac_ 02:48:56 INFO - Initialising browser for kraken test... 02:48:56 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpO3fMts/profile http://localhost:49272/getInfo.html) 02:48:59 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 02:48:59 INFO - PROCESS | 1672 | colorDepth:24 02:48:59 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 02:48:59 INFO - PROCESS | 1672 | __metrics 02:48:59 ERROR - PROCESS | 1672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 02:49:00 INFO - TEST-INFO | 1672: exit 0 02:49:00 INFO - Browser initialized. 02:49:00 INFO - Running cycle 1/1 for kraken test... 02:49:00 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpO3fMts/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:49:12 INFO - PROCESS | 1675 | RSS: Main: 220323840 02:49:12 INFO - PROCESS | 1675 | 02:49:15 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 02:49:16 INFO - PROCESS | 1675 | RSS: Main: 232529920 02:49:16 INFO - PROCESS | 1675 | 02:49:18 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 02:49:19 INFO - PROCESS | 1675 | RSS: Main: 241418240 02:49:19 INFO - PROCESS | 1675 | 02:49:23 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 02:49:23 INFO - PROCESS | 1675 | RSS: Main: 244596736 02:49:23 INFO - PROCESS | 1675 | 02:49:25 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 02:49:26 INFO - PROCESS | 1675 | RSS: Main: 245936128 02:49:26 INFO - PROCESS | 1675 | 02:49:28 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:49:29 INFO - PROCESS | 1675 | RSS: Main: 246640640 02:49:29 INFO - PROCESS | 1675 | 02:49:32 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 02:49:32 INFO - PROCESS | 1675 | RSS: Main: 247144448 02:49:32 INFO - PROCESS | 1675 | 02:49:35 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 02:49:36 INFO - PROCESS | 1675 | RSS: Main: 247287808 02:49:36 INFO - PROCESS | 1675 | 02:49:39 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 02:49:40 INFO - PROCESS | 1675 | RSS: Main: 247582720 02:49:40 INFO - PROCESS | 1675 | 02:49:41 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:49:42 INFO - PROCESS | 1675 | RSS: Main: 246878208 02:49:42 INFO - PROCESS | 1675 | 02:49:44 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:49:45 INFO - PROCESS | 1675 | RSS: Main: 246906880 02:49:45 INFO - PROCESS | 1675 | 02:49:47 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:49:48 INFO - PROCESS | 1675 | RSS: Main: 247287808 02:49:48 INFO - PROCESS | 1675 | 02:49:51 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:49:51 INFO - PROCESS | 1675 | RSS: Main: 247353344 02:49:51 INFO - PROCESS | 1675 | 02:49:54 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:49:55 INFO - PROCESS | 1675 | RSS: Main: 247599104 02:49:55 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 02:49:57 INFO - PROCESS | 1675 | RSS: Main: 250150912 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | __start_tp_report 02:49:57 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 02:49:57 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 02:49:57 INFO - PROCESS | 1675 | |i|pagename|runs| 02:49:57 INFO - PROCESS | 1675 | |0;ai-astar;116;123;138;177;155;121;158;98;153;100 02:49:57 INFO - PROCESS | 1675 | |1;audio-beat-detection;161;130;173;193;129;145;130;162;168;162 02:49:57 INFO - PROCESS | 1675 | |2;audio-dft;220;224;225;226;228;227;224;225;226;230 02:49:57 INFO - PROCESS | 1675 | |3;audio-fft;64;72;80;142;60;60;60;63;128;49 02:49:57 INFO - PROCESS | 1675 | |4;audio-oscillator;85;85;135;76;86;83;123;83;80;124 02:49:57 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;93;90;92;91;96;88;92;121;139;89 02:49:57 INFO - PROCESS | 1675 | |6;imaging-darkroom;97;98;98;122;113;97;99;110;121;98 02:49:57 INFO - PROCESS | 1675 | |7;imaging-desaturate;110;106;110;106;158;177;135;110;114;111 02:49:57 INFO - PROCESS | 1675 | |8;json-parse-financial;63;67;68;66;69;68;68;81;66;62 02:49:57 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;68;69;72;82;87;77;75;72;63;108 02:49:57 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;109;114;91;90;88;101;108;85;92;106 02:49:57 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;136;138;147;129;159;164;156;147;188;171 02:49:57 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;186;228;185;180;161;209;236;219;176;163 02:49:57 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;73;72;88;77;70;70;72;75;74;73 02:49:57 INFO - PROCESS | 1675 | __end_tp_report 02:49:57 INFO - PROCESS | 1675 | __start_cc_report 02:49:57 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1139 02:49:57 INFO - PROCESS | 1675 | __end_cc_report 02:49:57 INFO - PROCESS | 1675 | __startTimestamp1462787397691__endTimestamp 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | ------- Summary: start ------- 02:49:57 INFO - PROCESS | 1675 | Number of tests: 14 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:133.90 Median:154.00 stddev:26.48 (17.2%) stddev-sans-first:27.28 02:49:57 INFO - PROCESS | 1675 | Values: 116.0 123.0 138.0 177.0 155.0 121.0 158.0 98.0 153.0 100.0 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:155.30 Median:162.00 stddev:21.33 (13.2%) stddev-sans-first:22.53 02:49:57 INFO - PROCESS | 1675 | Values: 161.0 130.0 173.0 193.0 129.0 145.0 130.0 162.0 168.0 162.0 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:225.50 Median:226.00 stddev:2.68 (1.2%) stddev-sans-first:1.96 02:49:57 INFO - PROCESS | 1675 | Values: 220.0 224.0 225.0 226.0 228.0 227.0 224.0 225.0 226.0 230.0 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:77.80 Median:61.50 stddev:31.39 (51.0%) stddev-sans-first:32.90 02:49:57 INFO - PROCESS | 1675 | Values: 64.0 72.0 80.0 142.0 60.0 60.0 60.0 63.0 128.0 49.0 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:83.00 stddev:22.04 (26.5%) stddev-sans-first:23.01 02:49:57 INFO - PROCESS | 1675 | Values: 85.0 85.0 135.0 76.0 86.0 83.0 123.0 83.0 80.0 124.0 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:99.10 Median:91.50 stddev:16.97 (18.6%) stddev-sans-first:17.86 02:49:57 INFO - PROCESS | 1675 | Values: 93.0 90.0 92.0 91.0 96.0 88.0 92.0 121.0 139.0 89.0 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:105.30 Median:97.50 stddev:10.24 (10.5%) stddev-sans-first:10.41 02:49:57 INFO - PROCESS | 1675 | Values: 97.0 98.0 98.0 122.0 113.0 97.0 99.0 110.0 121.0 98.0 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:123.70 Median:112.50 stddev:24.92 (22.2%) stddev-sans-first:25.94 02:49:57 INFO - PROCESS | 1675 | Values: 110.0 106.0 110.0 106.0 158.0 177.0 135.0 110.0 114.0 111.0 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:67.80 Median:68.00 stddev:5.16 (7.6%) stddev-sans-first:5.17 02:49:57 INFO - PROCESS | 1675 | Values: 63.0 67.0 68.0 66.0 69.0 68.0 68.0 81.0 66.0 62.0 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:77.30 Median:73.50 stddev:12.82 (17.4%) stddev-sans-first:13.15 02:49:57 INFO - PROCESS | 1675 | Values: 68.0 69.0 72.0 82.0 87.0 77.0 75.0 72.0 63.0 108.0 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:98.40 Median:86.50 stddev:10.36 (12.0%) stddev-sans-first:10.26 02:49:57 INFO - PROCESS | 1675 | Values: 109.0 114.0 91.0 90.0 88.0 101.0 108.0 85.0 92.0 106.0 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:153.50 Median:157.50 stddev:17.87 (11.3%) stddev-sans-first:17.80 02:49:57 INFO - PROCESS | 1675 | Values: 136.0 138.0 147.0 129.0 159.0 164.0 156.0 147.0 188.0 171.0 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.30 Median:197.50 stddev:26.84 (13.6%) stddev-sans-first:28.30 02:49:57 INFO - PROCESS | 1675 | Values: 186.0 228.0 185.0 180.0 161.0 209.0 236.0 219.0 176.0 163.0 02:49:57 INFO - PROCESS | 1675 | 02:49:57 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:73.50 stddev:5.23 (7.1%) stddev-sans-first:5.53 02:49:57 INFO - PROCESS | 1675 | Values: 73.0 72.0 88.0 77.0 70.0 70.0 72.0 75.0 74.0 73.0 02:49:57 INFO - PROCESS | 1675 | -------- Summary: end -------- 02:49:57 INFO - PROCESS | 1675 | 02:49:58 INFO - TEST-INFO | 1675: exit 0 02:49:58 INFO - TEST-OK | kraken | took 62134ms 02:49:58 INFO - SUITE-END | took 1172s 02:49:58 INFO - Completed test suite (00:19:32) 02:49:58 INFO - javascript benchmark 02:49:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2733.398652296494, 2913.9854300728493, 3000.9849950750245, 3013.1412547423984, 3047.878084876605, 4841.467438581758, 4968.354113965189, 4980.352554167957, 4990.700557966516, 5010.799568017275, 3649.1424515238896, 4052.49343832021, 4328.588784065517, 4893.853184404465, 4978.900421991558, 3641.9635803642022, 3648.890533284006, 4581.060882519084, 4708.693934894238, 4719.693219940693, 4419.425474688286, 4677.672562920597, 4763.452202996665, 4801.519848015206, 4825.372701548794, 4539.7049191802425, 4598.609118224955, 4622.237330840408, 4669.6731228814, 4688.2264426369775, 4557.380477046314, 4585.128825523157, 4591.403117594725, 4628.861134165981, 4689.929651055203, 3510.648935106469, 4302.505211900609, 4501.392312037866, 4545.295479200696, 4619.214733495314, 4505.3917721107555, 4551.453825540923, 4598.632109431237, 4680.180968330528, 4724.7401392923375, 5350.759215835296, 5370.731463426852, 5391.523758707882, 5436.891262174734, 5470.261514695505, 4591.449026116854, 5238.899831035487, 5534.4742249486235, 5547.472990065938, 5573.749181286852, 5072.5688316493515, 5110.463401342842, 5115.7442127893455, 5118.360204974379, 5128.512791284822, 5604.215409842625, 5607.887842243133, 5621.15682647598, 5634.915476267859, 5683.8010669626365, 5181.98951204519, 5316.973415132977, 5340.652857564246, 5359.0, 5389.056915039852, 5477.835664930059, 5479.479449452296, 5492.093804522289, 5502.862428439321, 5523.392426833045, 5381.515663590296, 5398.433164517748, 5411.350637923396, 5421.626885545659, 5427.213054107211, 5140.434552199255, 5257.421683614799, 5339.786408543692, 5372.973135134377, 5422.945770542323, 4260.2970509866145, 4337.436133302651, 4350.0212452198, 4419.624331931757, 4464.888377790581, 2866.813657112281, 3211.518272259133, 3928.175083232496, 3951.644352008333, 3969.0275882408996, 4424.778761061934, 4664.836730714476, 4726.574608285271, 4842.467328593852, 4859.611231101503, 5329.387120481194, 5382.165764306539, 5395.62230643859, 5422.972885135628, 5452.209429632681, 3882.825272862728, 4974.90050198994, 4989.0520801048415, 5025.924611130836, 5198.92201616976, 3906.921861562753, 4520.4801447833925, 4702.600278976257, 4781.497942716034, 4785.904281914342, 5724.313082430135, 5738.426157384228, 5763.740631671585, 5800.013997620415, 5816.27296587918, 5466.262054622575, 5483.0, 5503.862403439946, 5518.034343989705, 5528.7235638218735, 5621.0, 5629.915551266652, 5705.743241554057, 5750.396208398058, 5780.884382312246, 5341.599380046589, 5371.5702743781185, 5375.973120134374, 5381.3004309441085, 5390.137577987503, 4175.791210439526, 5464.371597266365, 5475.972620136874, 5485.149801780731, 5505.504504594445, 5115.079285728604, 5180.7409629519125, 5204.973975130251, 5220.929709409486, 5231.346081739783, 5208.401033881152, 5247.055530004636, 5248.160294352885, 5261.289725886956, 5264.473552644705, 4890.777305673581, 5022.723750193774, 5085.415177254663, 5119.488051195, 5130.7178105204575, 4046.59534046593, 5234.895302094013, 5251.89496210066, 5287.3919499256535, 5331.920021199608, 5150.381954165524, 5173.534381905646, 5200.193969934666, 5226.372835259793, 5226.817061402832], "value": 4898.146415437556, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [440.9801558929848, 464.32904453065316, 482.4861522478561, 502.6833095150055, 518.5048278893657, 36623.26753464933, 37867.0, 37969.810150949204, 38076.238475230515, 38108.0, 40961.0, 41873.58126418735, 41881.0, 41998.79000605, 42042.0, 7833.9216607833905, 9309.069093090671, 9356.906430935705, 9394.201492873113, 9402.247820174369, 9345.299102567335, 9354.719358419225, 9456.479893605849, 9473.242140628767, 9478.90521094787, 8018.358531317481, 9449.622015119421, 9471.194948429391, 9495.240380769557, 9526.42841429513, 9417.717468475958, 9432.19826314764, 9475.857862132005, 9485.33602647815, 9532.95233523835, 9298.11667891556, 9331.440113593138, 9434.858477122849, 9448.952755236249, 9459.432434053979, 37864.48542058314, 37971.430428543594, 38421.0, 38455.0, 38493.42259866104, 2572.794176465878, 2925.297928497145, 2988.8505574721175, 3116.9532457013165, 3190.441672707267, 24586.770661467035, 24752.0, 25174.748252517424, 25405.74594254052, 25939.0, 2529.45703121096, 2589.7280785517437, 2598.701149367828, 2625.225558460259, 2625.973740262592, 360031.0, 362537.0, 363435.0, 366296.0, 367870.0, 33079.0, 33096.834515827264, 33300.1674958128, 33431.164220894185, 34307.828460857534, 33344.83327583395, 33944.30278486066, 33976.49035264473, 34224.486632700035, 34481.482777758356, 14651.633709157357, 14970.17664028489, 15156.696866062837, 15171.013884097498, 15181.316840742193, 10903.727406814893, 12499.875001250053, 12836.101472896808, 12864.292463914575, 12870.034747393984, 7876.881846772303, 7941.960290198512, 7970.242826931432, 8004.759857204293, 8006.039275286887, 2833.688294287627, 3372.696457318804, 3375.9907364890532, 3377.4089534331392, 3401.2347221875375, 808.7412028151053, 814.3688641303038, 856.1737922904302, 861.5390765940317, 864.7232885476587, 26212.60681089785, 26635.934562617666, 26860.865695671786, 26981.0, 27149.864250679017, 915.4370062411703, 919.2416256588341, 938.7465384346189, 991.2317953586029, 1022.1873610479687, 13698.452061917611, 13701.314934253247, 13707.520236791665, 13951.0, 14005.5098071567, 631227.0, 632070.0, 635432.0, 637279.0, 641777.0, 57930.42069579334, 58487.70756146192, 58552.41447585555, 58725.0, 59051.704741476016, 1216.9634910952686, 1404.676924307415, 1421.1899217445957, 1435.3038776193537, 1452.3391856705173, 25660.743392565837, 26389.0, 26554.203373898814, 26997.73002269952, 27217.183484495497, 43847.56152438435, 47041.764791175825, 49983.0, 50519.74740126423, 50542.241866371296, 491644.0, 494835.0, 498665.0, 499220.0, 501038.0, 21709.457263567907, 25226.36934076663, 25506.852191651793, 26275.817588208196, 26391.20826375212, 32246.032619021466, 44000.11999760051, 44485.33272000987, 44934.55065449304, 45672.77163614161, 27948.72051279461, 34812.08533530579, 36669.63330366662, 37946.0, 38269.42595861009, 35826.641733583376, 36365.81817091003, 36529.0, 37001.44497832588, 37070.0, 33741.83129084437, 34003.65996340005, 36254.27491450103, 37303.25393492169, 37866.242675146896, 29642.555361670024, 30883.0735077948, 31301.530477042408, 31771.046868593978, 31998.840005799822, 23920.88039559791, 23941.281761547183, 24077.398065048514, 24120.0, 24305.0], "value": 17169.3422567824, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.4565266232308, 370.353732736375, 370.486875677187, 381.1405281091139, 385.1661153602451, 19154.13806378713, 19879.602407951832, 20052.899735501338, 20989.37031889044, 21024.684629730567, 20428.89785551074, 20555.897220513878, 20639.071241794118, 20746.89626551865, 20753.896230518865, 5603.047481928072, 5968.79109231177, 5979.133025711291, 5979.5515336349945, 5995.340512543617, 5580.972095139518, 5824.650520968734, 5884.794032208873, 5918.1418694289305, 5934.1098835174635, 5838.0, 5873.0603103503445, 5887.705614719247, 5897.911531327033, 5911.379305172959, 5564.137558678391, 5747.109198074305, 5823.417658234185, 5867.061270196769, 5869.002269614176, 5106.387233531963, 5107.770150343244, 5115.0, 5115.309433226504, 5143.948560514385, 25089.12188073409, 25361.36596585082, 25672.229833105037, 25673.35816604581, 25709.22872313815, 1782.4652604218682, 2078.511549785799, 2441.6825812644242, 2526.027479420418, 2885.581590669341, 14363.281835908267, 14367.137971721626, 14530.0, 14875.404983800636, 15124.697506049928, 1691.1121661127884, 1760.4542591796585, 1772.8758986870923, 1776.7068433708423, 1782.3850771483817, 95494.0, 96989.06021879593, 98745.0, 99569.5064574032, 99781.0, 10553.0, 10651.254412191072, 11034.0, 11313.0, 11487.310761354345, 13831.30843457823, 13855.515056972958, 13929.024968252328, 13943.860561394458, 13952.720945581235, 7377.372923301579, 8686.695965641173, 8813.691520796741, 8922.241609463139, 8983.55082245885, 8182.427230093836, 8337.041240257327, 9073.13805188506, 9088.363814532919, 9199.770005749777, 6735.191776986793, 6807.727690892408, 6820.249772525019, 6839.863202735917, 6842.863142737117, 1817.5183576352276, 1873.5597134673342, 1897.6204759048246, 1901.6767149584605, 1923.710784166837, 690.944724422045, 780.2665494435327, 812.5530957973091, 883.0948278014957, 902.2691619787993, 15249.923750381176, 15762.605934851721, 18874.245030198912, 19694.507637308896, 19825.20699172017, 851.748734123435, 862.2799962031773, 892.2371372476568, 892.4377642085575, 903.6204793986542, 10263.794724105626, 10465.319754216103, 10485.0, 10537.894621053845, 10550.525226364833, 535456.0, 539488.0, 541337.0, 550700.0, 551177.0, 61307.6934615333, 61673.0, 78582.42835143379, 78869.81695274575, 101121.0, 983.5672304186136, 993.1062044160313, 995.1889210293732, 999.4802702594756, 1003.1673710820002, 15129.417980451639, 17040.914795425942, 17183.31266749316, 17302.5674358137, 17383.652326953135, 44331.0, 44744.77627611841, 45055.0, 45373.0, 46166.07667846692, 58622.0, 62369.45950255167, 62413.375866240756, 62755.0, 62903.0, 7877.606119694107, 8054.476459030145, 8074.475159114639, 8138.470999385021, 8215.71624064803, 12307.0, 12602.180858244184, 12662.556810511956, 12904.41930113166, 12945.935270323587, 9845.818501779831, 10018.849717254392, 10165.898341016495, 10191.84712229332, 10447.268691191848, 9858.112769850748, 10506.0, 10608.89391106079, 10693.411862347335, 10905.83641245366, 9957.850632240377, 10822.783544329226, 11029.338239705643, 11093.944530277297, 11345.0, 6269.902767015845, 7899.723509677134, 8009.639566219417, 8243.587820608825, 8297.543635100128, 9058.320625952949, 10310.536025878704, 10329.741756455847, 10405.757218796176, 10459.110975567162], "value": 9203.26246681234, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.4055516960461, 1452.0489079652825, 1607.7025750236207, 1741.5210817025315, 1802.22504323141, 7672.539647621133, 8238.50568965861, 8414.705485308015, 8647.956760216206, 8676.47941123533, 8257.25684688378, 8298.336133109351, 8322.75031749047, 8370.832583348329, 8373.874391884126, 2340.7659234076627, 2353.7175538935353, 2370.5258948210426, 2374.0859768988976, 2408.2414039577566, 2310.919117830876, 2336.100601268507, 2358.422186564294, 2360.6931098957198, 2373.1456675596773, 2320.640300753386, 2326.6510023496444, 2375.740857345601, 2384.7138343398815, 2391.712994440661, 2342.7891489766002, 2360.1385494294545, 2378.512404956988, 2378.7502312257225, 2385.962106483668, 2053.661145910923, 2300.620397634388, 2380.6310021946624, 2385.105585405473, 2395.1856368834506, 8461.83076338476, 8464.746057618282, 8497.787555311104, 8498.660053597849, 8518.829623407559, 5129.435762066169, 5322.33470816148, 5338.62629615927, 5350.973245133789, 5366.8926621467745, 3422.691957723817, 3431.159365955357, 3433.759636825423, 3489.0928358626684, 3490.6683865032783, 981.4749109226601, 989.648674720471, 1004.2167109654408, 1013.5185786751308, 1027.2347101409498, 11334.036606888425, 11342.376169310684, 11424.600138995178, 11513.791051939594, 11586.826197607043, 4669.9259170391, 4724.291356296583, 4789.832355867528, 4809.879753006203, 4868.0, 8389.161083891684, 9926.602935882629, 9955.80088398228, 10008.899911000797, 10139.695809125738, 5139.794408223704, 5283.181106928432, 5304.628675992721, 5376.892462150735, 5379.273798037293, 3686.705063594906, 3961.4057891316534, 4216.873493795191, 4668.439787225487, 4827.179379505493, 3960.128771670228, 4003.0, 4025.355943049098, 4103.2409004334295, 4106.733062350938, 1162.6918866500384, 1315.9407826647823, 1320.3002408723391, 1328.6080606221187, 1331.7003674173234, 795.411395567276, 797.6849144587856, 798.1260519730886, 806.0005593064558, 819.1153554161491, 5418.83743487696, 5439.755211015514, 5548.167774833807, 5602.075657516464, 5711.3146422428745, 873.3537182485043, 887.9644814207488, 895.0154829687292, 897.6185121323412, 909.9590518426687, 3060.5562193482265, 3630.9636903631153, 3677.687396571321, 3679.35611268027, 3692.3169213695014, 10121.69634910954, 10456.738581535523, 10458.111060559786, 10475.214358922958, 10491.003354681294, 8556.443831150955, 10324.587016519405, 10370.22223333253, 10404.739881502872, 10429.895701043044, 8070.717524886601, 8092.271695547429, 8128.59357032146, 8184.345252379795, 8191.836163276701, 4784.114938736345, 5013.373328333958, 5069.493050695049, 5078.060558796635, 5079.528470895712, 9065.320100992583, 9182.724518264462, 9319.813603728024, 9371.906280937103, 9490.098440648128, 9428.764280893032, 9532.189763869872, 9624.422534647663, 9654.227661787181, 9658.75853103678, 6577.3422657733845, 7297.270272972873, 7421.220771818881, 7680.5391676499585, 7921.683132674629, 7902.2492863180205, 7921.405894558046, 7944.324732397811, 7963.323117535075, 8023.558704271321, 6935.202451718117, 7051.929480705127, 7147.392471639969, 7182.569045857265, 7235.855282894207, 7059.682314295766, 7075.6815943281645, 7170.928290717026, 7248.96375518119, 7264.963675181591, 6420.743170273324, 6555.246146693191, 6628.0, 6630.30381809903, 6670.999350097524, 4390.626796722315, 4442.200403927324, 4444.28891377378, 4515.68390212682, 4605.40129783126, 4526.049529598821, 4537.0, 4601.585857272861, 4613.169629466594, 4622.861314160446], "value": 4416.790941109421, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.09179360395564, 293.1571731272591, 295.3089769938344, 297.4140942343583, 300.35273984563275, 5435.293411856458, 5459.5905307101975, 5468.808591699286, 5527.695976721278, 5532.529734972522, 5461.098918678424, 5462.562994960404, 5484.040292948728, 5485.259489968853, 5502.202180683798, 3882.8058597070103, 3942.408638704203, 4103.507579090513, 4119.485064366954, 4120.917581648365, 4069.633732964033, 4096.32410652242, 4101.077257617042, 4114.341705327149, 4116.485439320085, 4027.2549578327958, 4031.0728532437556, 4038.293298672735, 4039.0, 4062.329715596923, 4042.8787136385954, 4062.1875624875142, 4064.3700226464944, 4110.116324990141, 4119.876403707894, 3594.101474631342, 3597.730170237243, 3601.207734298476, 3613.6747692707518, 3613.7470377073614, 3985.864028751821, 4315.5900189481945, 5018.272350509155, 5057.115004874132, 5071.492850714899, 4587.220172570671, 4620.9768951155365, 4839.37088178538, 4842.44311904132, 4852.8544143675745, 4632.583067523905, 5849.970750146222, 5881.529477641779, 5918.082697181943, 5949.613275137102, 3330.350581636582, 4093.140440507497, 4094.324436467979, 4107.281225785506, 4121.175764847043, 4943.950560494385, 6163.845903852395, 6277.560570760049, 6285.43431091204, 6306.5900716452825, 4144.3990621360335, 4146.150039241948, 4150.481189851269, 4167.97916010418, 4172.520160181557, 3454.6545345465756, 4235.682323825726, 4247.384129301296, 4248.4901811782165, 4254.765987870697, 4584.174848527297, 4821.63837712173, 4835.709857408567, 4925.655204135748, 4935.0, 4856.344393506902, 5919.023361145449, 5931.6441013539325, 5949.3753155918, 5968.9701551492835, 1425.8788003019858, 1467.4350375105662, 1468.0604413175577, 1472.4551915791012, 1477.3573495529265, 948.1892981500855, 953.666216824106, 975.2100798353357, 985.1773768902902, 985.8225519406576, 5182.870428239325, 5208.0, 5230.607704422184, 5244.265802787613, 5256.710880901587, 6065.8180254592435, 6088.878222435526, 6119.112728654321, 6119.938800611937, 6152.415520525543, 4962.255661650781, 5190.039842629126, 5213.556847667915, 5218.008578370098, 5226.45122262157, 3118.7972781769113, 3244.026791962402, 3263.7552183586326, 3278.8032718036993, 3294.4893541501106, 5516.696581688046, 6272.964960781511, 6514.283428822826, 6518.967405162944, 6594.505412094112, 6420.101185833987, 6424.582402143846, 6436.3241859605005, 6460.580062295936, 6489.5781774184525, 6482.189726284215, 6529.836754081091, 6547.508936829757, 6547.934520654828, 6549.148610680581, 4939.777710003131, 5769.336526299529, 6126.724297406717, 6185.134081228542, 6226.750929962932, 6131.995322292134, 6170.290416602148, 6185.783497577563, 6214.782482613087, 6217.813465596039, 6526.216853977553, 6531.444827189742, 6536.803895882941, 6551.344865513411, 6560.343965603402, 3914.8042597870563, 3966.9404958926216, 3968.682505399619, 3980.641742243212, 3985.362342025262, 4847.684900481458, 6103.572749907464, 6131.386861313833, 6136.631802091889, 6154.569180157346, 5583.972080139573, 5886.146508756292, 5902.5277977762535, 5920.052791553331, 5968.522518198447, 5873.911891321548, 5888.205092312483, 5892.499137573354, 5894.086416605433, 5909.645421274738, 4581.785826755979, 4875.731834748981, 5389.434109418455, 5833.154192642128, 5929.962256605163, 4651.139539185261, 4709.976450117728, 5895.970520147372, 5938.228030356199, 5958.493528050164, 4598.402207712976, 5424.7558859850615, 5753.8561535961935, 5775.8556036099435, 5799.913001305068], "value": 4316.890035300934, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.9125318082057, 670.2191946382463, 704.5596502186137, 712.8895021271703, 728.0862517540486, 14548.0, 14586.197759123244, 14668.853311466883, 14773.630659233524, 14791.186484743335, 10883.455827208627, 13473.258970756604, 13503.93248033761, 13505.324733763322, 13530.594082177526, 696.4915611603533, 867.154524338771, 874.1433395272621, 890.4301247201796, 893.5532233883058, 883.4213590098464, 892.9330300227476, 894.575076838503, 896.6592694775976, 897.9583682927805, 947.7062110745657, 948.3598570964606, 948.4214629076258, 950.5484894675043, 955.0926619711267, 941.0260380506178, 944.8252073366415, 946.4179529589322, 946.5787724462617, 949.0462085603992, 698.7728988078894, 705.1186017478152, 705.8023753349071, 707.9504434689574, 707.9575225486436, 14676.853231467656, 14699.044562103536, 14731.04248223862, 14734.557963261119, 14777.039492432958, 9872.0, 12045.93977030118, 12134.635960921187, 12256.0807939404, 12267.754644907141, 9549.331546791727, 9660.565274562661, 9717.076877696607, 9814.263930205192, 9820.67036315496, 3306.7354611631013, 3394.4908263760385, 3405.5743032120986, 3452.8446219920165, 3455.1189446691183, 64625.67687161581, 69005.3099469004, 76726.23273767247, 77668.61165694192, 78126.82809757857, 8903.73288801337, 9201.631934722536, 9203.677871274473, 9302.348835581444, 9378.718638440858, 21148.259810906544, 21283.574328513343, 21352.039158237916, 21381.78618213829, 21629.0, 7004.894926576106, 7097.822554436078, 7108.92891071093, 7278.854422911512, 7333.74331898381, 3785.981070094687, 3834.175652234783, 3868.0, 3869.3228684980018, 3904.1996390739832, 3223.62928263248, 3457.567804024497, 3478.9652103479148, 3517.0152357339985, 3517.3317069756663, 644.3588629313863, 644.3878315600199, 644.9806505804834, 646.689588997284, 647.0811447744214, 509.73239049499307, 530.1729302288443, 531.7235037780332, 538.2894579155477, 559.2031355318736, 7247.383972362408, 7530.397568194531, 7537.133229678548, 7746.30283274508, 7749.690012399554, 526.476156224559, 526.5576915391014, 527.7227380311177, 529.6689569019363, 544.4827413956677, 3453.7755045921936, 3541.4333706606817, 3544.680978711928, 3570.785752854785, 3708.2768860072565, 83850.322993541, 92945.0, 93304.0, 94674.52662736642, 94810.0518994815, 55221.44778552243, 71470.0, 71982.64008679923, 72725.6363718178, 77467.61266193746, 66323.00515492175, 66491.66754166198, 67045.0, 67402.32597674058, 67635.64728705496, 6143.508519318533, 7774.805629859299, 8056.274935255855, 8098.392620553364, 8106.83786324282, 34044.829775851955, 45732.542674572825, 46357.76821115873, 46779.29831052605, 47681.761591191826, 62798.37201628108, 65408.345916540224, 66092.33907660862, 66421.66789166024, 66457.66771166306, 10385.688429347132, 10396.517166748921, 10585.153187745116, 10707.732306692395, 10776.622818201638, 11282.323060616389, 11369.488373023069, 11553.653390397965, 11592.88407115918, 12073.154879158727, 10252.574536484377, 10502.159827213653, 10619.178735797757, 10785.298955567863, 10795.730106747395, 10690.305130166515, 10771.192160588143, 10849.728756781144, 10859.891401086204, 11173.832392513956, 10326.535305911402, 10736.248462607542, 10966.780664386828, 10980.286281391684, 10987.175961802737, 9080.727578172666, 9298.442093474414, 9332.673356432493, 9335.439873607605, 9486.905130948879, 8469.957650211709, 8713.607887644945, 8753.47479151253, 9202.016525134293, 9211.815763684555], "value": 6582.968778326851, "unit": "score"}], "name": "dromaeo_css", "value": 6780.235252647574, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 123.0, 138.0, 177.0, 155.0, 121.0, 158.0, 98.0, 153.0, 100.0], "value": 133.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 130.0, 173.0, 193.0, 129.0, 145.0, 130.0, 162.0, 168.0, 162.0], "value": 155.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 224.0, 225.0, 226.0, 228.0, 227.0, 224.0, 225.0, 226.0, 230.0], "value": 225.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 80.0, 142.0, 60.0, 60.0, 60.0, 63.0, 128.0, 49.0], "value": 77.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 135.0, 76.0, 86.0, 83.0, 123.0, 83.0, 80.0, 124.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 92.0, 91.0, 96.0, 88.0, 92.0, 121.0, 139.0, 89.0], "value": 99.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 98.0, 122.0, 113.0, 97.0, 99.0, 110.0, 121.0, 98.0], "value": 105.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 110.0, 106.0, 158.0, 177.0, 135.0, 110.0, 114.0, 111.0], "value": 123.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 68.0, 66.0, 69.0, 68.0, 68.0, 81.0, 66.0, 62.0], "value": 67.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 72.0, 82.0, 87.0, 77.0, 75.0, 72.0, 63.0, 108.0], "value": 77.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 114.0, 91.0, 90.0, 88.0, 101.0, 108.0, 85.0, 92.0, 106.0], "value": 98.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 138.0, 147.0, 129.0, 159.0, 164.0, 156.0, 147.0, 188.0, 171.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 228.0, 185.0, 180.0, 161.0, 209.0, 236.0, 219.0, 176.0, 163.0], "value": 194.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 88.0, 77.0, 70.0, 70.0, 72.0, 75.0, 74.0, 73.0], "value": 74.4, "unit": "score"}], "name": "kraken", "value": 1682.3000000000002, "alertThreshold": 2.0}]} 02:49:58 INFO - Return code: 0 02:49:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:49:59 ERROR - # TBPL SUCCESS # 02:49:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:49:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:49:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:49:59 INFO - 2016-05-09 02:49:59.029 screenresolution[1680:10586] starting screenresolution argv=screenresolution get 02:49:59 INFO - 2016-05-09 02:49:59.032 screenresolution[1680:10586] Display 0: 1600x1200x32@60 02:49:59 INFO - 2016-05-09 02:49:59.038 screenresolution[1681:10589] starting screenresolution argv=screenresolution list 02:49:59 INFO - Available Modes on Display 0 02:49:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:49:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:49:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:49:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:49:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:49:59 INFO - Intel Iris: 02:49:59 INFO - Chipset Model: Intel Iris 02:49:59 INFO - Type: GPU 02:49:59 INFO - Bus: Built-In 02:49:59 INFO - VRAM (Dynamic, Max): 1536 MB 02:49:59 INFO - Vendor: Intel (0x8086) 02:49:59 INFO - Device ID: 0x0a2e 02:49:59 INFO - Revision ID: 0x0009 02:49:59 INFO - Displays: 02:49:59 INFO - Display: 02:49:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:49:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:49:59 INFO - Display Serial Number: 1600x1200 60 02:49:59 INFO - Main Display: Yes 02:49:59 INFO - Mirror: Off 02:49:59 INFO - Online: Yes 02:49:59 INFO - Rotation: Supported 02:49:59 INFO - Return code: 0 02:49:59 INFO - Running post-action listener: _resource_record_post_action 02:49:59 INFO - Running post-run listener: _resource_record_post_run 02:50:00 INFO - Total resource usage - Wall time: 1207s; CPU: 54.0%; Read bytes: 37234176; Write bytes: 653681664; Read time: 390; Write time: 1781 02:50:00 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 356346368; Write bytes: 346630144; Read time: 28433; Write time: 630 02:50:00 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 30109184; Write bytes: 296262656; Read time: 302; Write time: 1117 02:50:00 INFO - Running post-run listener: _upload_blobber_files 02:50:00 INFO - Blob upload gear active. 02:50:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:50:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:50:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:50:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:50:00 INFO - (blobuploader) - INFO - Open directory for files ... 02:50:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:50:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:50:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:50:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:50:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:50:02 INFO - (blobuploader) - INFO - Done attempting. 02:50:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:50:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:50:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:50:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:50:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:50:03 INFO - (blobuploader) - INFO - Done attempting. 02:50:03 INFO - (blobuploader) - INFO - Iteration through files over. 02:50:03 INFO - Return code: 0 02:50:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:50:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:50:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/681b2609b6a0007c4610baef482151e3717435c3acce44e5cebae70e9e97c22291e52f0e7795e18f8a4f9b563b794d5ef20eb19f297fb4d50a801ed90afba6ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb86d8f8d9d9413c1d68d030829c78eaecc0742af8a5209da053a343afcf0cd80e67038635e28d414b11bbec208a95ec7044a95e75c129e55691ee331401fd35"} 02:50:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:50:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:50:03 INFO - Contents: 02:50:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/681b2609b6a0007c4610baef482151e3717435c3acce44e5cebae70e9e97c22291e52f0e7795e18f8a4f9b563b794d5ef20eb19f297fb4d50a801ed90afba6ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb86d8f8d9d9413c1d68d030829c78eaecc0742af8a5209da053a343afcf0cd80e67038635e28d414b11bbec208a95ec7044a95e75c129e55691ee331401fd35"} 02:50:03 INFO - Running post-run listener: copy_logs_to_upload_dir 02:50:03 INFO - Copying logs to upload dir... 02:50:03 INFO - mkdir: /builds/slave/test/build/upload/logs 02:50:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.400780 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-09 02:50:03.084429) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 02:50:03.088085) ========= 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.NyAB9RrLb5/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.dbBAjgculM/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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/681b2609b6a0007c4610baef482151e3717435c3acce44e5cebae70e9e97c22291e52f0e7795e18f8a4f9b563b794d5ef20eb19f297fb4d50a801ed90afba6ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb86d8f8d9d9413c1d68d030829c78eaecc0742af8a5209da053a343afcf0cd80e67038635e28d414b11bbec208a95ec7044a95e75c129e55691ee331401fd35"} 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.011977 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/681b2609b6a0007c4610baef482151e3717435c3acce44e5cebae70e9e97c22291e52f0e7795e18f8a4f9b563b794d5ef20eb19f297fb4d50a801ed90afba6ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb86d8f8d9d9413c1d68d030829c78eaecc0742af8a5209da053a343afcf0cd80e67038635e28d414b11bbec208a95ec7044a95e75c129e55691ee331401fd35"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 02:50:03.117556) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 02:50:03.117913) ========= 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.NyAB9RrLb5/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.dbBAjgculM/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.005497 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 02:50:03.166732) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 02:50:03.167056) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 02:50:03.167517) ========= ========= Total master_lag: 0.15 =========