builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0310 starttime: 1465205491.14 results: success (0) buildid: 20160606003529 builduid: 5bf5b41c7b4a4cc698ee9abdcebeaa3c revision: 7f8d31a531ca31d5206f0a37528c36d1695ad767 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 02:31:31.137097) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 02:31:31.137545) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 02:31:31.137922) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 02:31:31.161662) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:31:31.162087) ========= 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.Z7P5OnW4WJ/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.50DGKGbyve/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-06 02:31:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.8M=0.001s 2016-06-06 02:31:31 (18.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.080205 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:31:31.263367) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:31:31.263691) ========= 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.Z7P5OnW4WJ/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.50DGKGbyve/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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.024849 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:31:31.312293) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 02:31:31.312659) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7f8d31a531ca31d5206f0a37528c36d1695ad767 --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 7f8d31a531ca31d5206f0a37528c36d1695ad767 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Z7P5OnW4WJ/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.50DGKGbyve/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-06 02:31:31,396 truncating revision to first 12 chars 2016-06-06 02:31:31,396 Setting DEBUG logging. 2016-06-06 02:31:31,396 attempt 1/10 2016-06-06 02:31:31,396 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7f8d31a531ca?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 02:31:32,770 unpacking tar archive at: mozilla-aurora-7f8d31a531ca/testing/mozharness/ program finished with exit code 0 elapsedTime=1.643360 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 02:31:32.976423) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 02:31:32.976754) ========= script_repo_revision: 7f8d31a531ca31d5206f0a37528c36d1695ad767 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 02:31:32.977147) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 02:31:32.977441) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 02:31:32.995150) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 11 secs) (at 2016-06-06 02:31:32.995541) ========= /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.Z7P5OnW4WJ/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.50DGKGbyve/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:31:33 INFO - MultiFileLogger online at 20160606 02:31:33 in /builds/slave/test 02:31:33 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:31:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:31:33 INFO - {'append_to_log': False, 02:31:33 INFO - 'base_work_dir': '/builds/slave/test', 02:31:33 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:31:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:31:33 INFO - 'branch': 'Mozilla-Aurora', 02:31:33 INFO - 'buildbot_json_path': 'buildprops.json', 02:31:33 INFO - 'config_files': ('talos/mac_config.py',), 02:31:33 INFO - 'default_actions': ('clobber', 02:31:33 INFO - 'read-buildbot-config', 02:31:33 INFO - 'download-and-extract', 02:31:33 INFO - 'populate-webroot', 02:31:33 INFO - 'create-virtualenv', 02:31:33 INFO - 'install', 02:31:33 INFO - 'run-tests'), 02:31:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:31:33 INFO - 'download_minidump_stackwalk': True, 02:31:33 INFO - 'download_symbols': 'ondemand', 02:31:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:31:33 INFO - 'tooltool.py': '/tools/tooltool.py', 02:31:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:31:33 INFO - '/tools/misc-python/virtualenv.py')}, 02:31:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:31:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:31:33 INFO - 'installer_path': 'installer.exe', 02:31:33 INFO - 'log_level': 'info', 02:31:33 INFO - 'log_name': 'talos', 02:31:33 INFO - 'log_to_console': True, 02:31:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:31:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:31:33 INFO - 'opt_config_files': (), 02:31:33 INFO - 'pip_index': False, 02:31:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:31:33 INFO - 'cmd': ('bash', 02:31:33 INFO - '-c', 02:31:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:31:33 INFO - 'enabled': True, 02:31:33 INFO - 'halt_on_failure': False, 02:31:33 INFO - 'name': 'check_screen_resolution'},), 02:31:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:31:33 INFO - 'cmd': ('bash', 02:31:33 INFO - '-c', 02:31:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:31:33 INFO - 'enabled': True, 02:31:33 INFO - 'halt_on_failure': False, 02:31:33 INFO - 'name': 'check_screen_resolution'},), 02:31:33 INFO - 'run_cmd_checks_enabled': True, 02:31:33 INFO - 'sps_profile': False, 02:31:33 INFO - 'sps_profile_interval': 0, 02:31:33 INFO - 'suite': 'dromaeojs-e10s', 02:31:33 INFO - 'system_bits': '32', 02:31:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:31:33 INFO - 'title': 't-yosemite-r7-0310', 02:31:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:31:33 INFO - 'use_talos_json': True, 02:31:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:31:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:31:33 INFO - 'work_dir': 'build'} 02:31:33 INFO - ##### 02:31:33 INFO - ##### Running clobber step. 02:31:33 INFO - ##### 02:31:33 INFO - Running pre-action listener: _resource_record_pre_action 02:31:33 INFO - Running main action method: clobber 02:31:33 INFO - rmtree: /builds/slave/test/build 02:31:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:31:34 INFO - Running post-action listener: _resource_record_post_action 02:31:34 INFO - ##### 02:31:34 INFO - ##### Running read-buildbot-config step. 02:31:34 INFO - ##### 02:31:34 INFO - Running pre-action listener: _resource_record_pre_action 02:31:34 INFO - Running main action method: read_buildbot_config 02:31:34 INFO - Using buildbot properties: 02:31:34 INFO - { 02:31:34 INFO - "project": "", 02:31:34 INFO - "product": "firefox", 02:31:34 INFO - "script_repo_revision": "production", 02:31:34 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:31:34 INFO - "repository": "", 02:31:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:31:34 INFO - "buildid": "20160606003529", 02:31:34 INFO - "pgo_build": "False", 02:31:34 INFO - "basedir": "/builds/slave/test", 02:31:34 INFO - "buildnumber": 22, 02:31:34 INFO - "slavename": "t-yosemite-r7-0310", 02:31:34 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 02:31:34 INFO - "platform": "yosemite_r7", 02:31:34 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:31:34 INFO - "branch": "mozilla-aurora", 02:31:34 INFO - "repo_path": "releases/mozilla-aurora", 02:31:34 INFO - "revision": "7f8d31a531ca31d5206f0a37528c36d1695ad767", 02:31:34 INFO - "stage_platform": "macosx64", 02:31:34 INFO - "builduid": "5bf5b41c7b4a4cc698ee9abdcebeaa3c", 02:31:34 INFO - "slavebuilddir": "test" 02:31:34 INFO - } 02:31:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:31:34 INFO - Running post-action listener: _resource_record_post_action 02:31:34 INFO - ##### 02:31:34 INFO - ##### Running download-and-extract step. 02:31:34 INFO - ##### 02:31:34 INFO - Running pre-action listener: _resource_record_pre_action 02:31:34 INFO - Running main action method: download_and_extract 02:31:34 INFO - mkdir: /builds/slave/test/build/tests 02:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:34 INFO - https://queue.taskcluster.net/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:31:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:31:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:31:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/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:31:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/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:31:36 INFO - Downloaded 1199 bytes. 02:31:36 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:31:36 INFO - Using the following test package requirements: 02:31:36 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:31:36 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:36 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:31:36 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:36 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:31:36 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:36 INFO - u'jsshell-mac.zip'], 02:31:36 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:36 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:31:36 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:31:36 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:36 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:31:36 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:36 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:31:36 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:36 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:31:36 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:36 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:31:36 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:31:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:36 INFO - https://queue.taskcluster.net/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:31:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:31:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:31:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/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:31:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/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:31:41 INFO - Downloaded 18466868 bytes. 02:31:41 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:31:41 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:31:44 INFO - Return code: 0 02:31:44 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:31:44 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:31:44 INFO - https://queue.taskcluster.net/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:31:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:31:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:31:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/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:31:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/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:31:45 INFO - Downloaded 18466868 bytes. 02:31:45 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:31:45 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:31:49 INFO - Return code: 0 02:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:49 INFO - https://queue.taskcluster.net/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:31:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:31:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:31:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/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:31:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/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:31:50 INFO - Downloaded 10840145 bytes. 02:31:50 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:31:50 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:31:50 INFO - Return code: 0 02:31:50 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:31:50 INFO - https://queue.taskcluster.net/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:31:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:31:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:31:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:31:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/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:32:22 WARNING - Socket error when accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg: The read operation timed out 02:32:22 INFO - retry: attempt #1 caught exception: The read operation timed out 02:32:22 INFO - retry: Failed, sleeping 30 seconds before retrying 02:32:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #2 02:32:55 INFO - Downloaded 114169118 bytes. 02:32:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:32:55 INFO - mkdir: /builds/slave/test/properties 02:32:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:32:55 INFO - Writing to file /builds/slave/test/properties/build_url 02:32:55 INFO - Contents: 02:32:55 INFO - build_url:https://queue.taskcluster.net/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:32:56 INFO - Running post-action listener: _resource_record_post_action 02:32:56 INFO - Running post-action listener: set_extra_try_arguments 02:32:56 INFO - ##### 02:32:56 INFO - ##### Running populate-webroot step. 02:32:56 INFO - ##### 02:32:56 INFO - Running pre-action listener: _resource_record_pre_action 02:32:56 INFO - Running main action method: populate_webroot 02:32:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:32:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:32:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:32:56 INFO - '--tppagecycles', 02:32:56 INFO - '7'], 02:32:56 INFO - 'tests': ['tsvgm']}}, 02:32:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:32:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:32:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:32:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:32:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:32:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:32:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:56 INFO - 'talos_options': ['--disable-e10s'], 02:32:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:32:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:32:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:56 INFO - 'talos_options': ['--disable-e10s'], 02:32:56 INFO - 'tests': ['damp', 'tps']}, 02:32:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:56 INFO - 'tests': ['damp', 'tps']}, 02:32:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:32:56 INFO - 'tests': ['dromaeo_dom']}, 02:32:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:32:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:32:56 INFO - 'tests': ['a11yr', 02:32:56 INFO - 'ts_paint', 02:32:56 INFO - 'tpaint', 02:32:56 INFO - 'sessionrestore', 02:32:56 INFO - 'sessionrestore_no_auto_restore', 02:32:56 INFO - 'tabpaint']}, 02:32:56 INFO - 'other-e10s': {'tests': ['a11yr', 02:32:56 INFO - 'ts_paint', 02:32:56 INFO - 'tpaint', 02:32:56 INFO - 'sessionrestore', 02:32:56 INFO - 'sessionrestore_no_auto_restore', 02:32:56 INFO - 'tabpaint']}, 02:32:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:32:56 INFO - 'ts_paint', 02:32:56 INFO - 'tpaint', 02:32:56 INFO - 'sessionrestore', 02:32:56 INFO - 'sessionrestore_no_auto_restore', 02:32:56 INFO - 'tabpaint']}, 02:32:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:32:56 INFO - 'ts_paint', 02:32:56 INFO - 'tpaint', 02:32:56 INFO - 'sessionrestore', 02:32:56 INFO - 'sessionrestore_no_auto_restore', 02:32:56 INFO - 'tabpaint']}, 02:32:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:32:56 INFO - 'tests': ['a11yr', 02:32:56 INFO - 'ts_paint', 02:32:56 INFO - 'tpaint', 02:32:56 INFO - 'sessionrestore', 02:32:56 INFO - 'sessionrestore_no_auto_restore', 02:32:56 INFO - 'tabpaint']}, 02:32:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:32:56 INFO - 'tests': ['a11yr', 02:32:56 INFO - 'ts_paint', 02:32:56 INFO - 'tpaint', 02:32:56 INFO - 'sessionrestore', 02:32:56 INFO - 'sessionrestore_no_auto_restore', 02:32:56 INFO - 'tabpaint']}, 02:32:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:32:56 INFO - 'tests': ['tsvgx', 02:32:56 INFO - 'tsvgr_opacity', 02:32:56 INFO - 'tart', 02:32:56 INFO - 'tscrollx', 02:32:56 INFO - 'cart']}, 02:32:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:32:56 INFO - 'tsvgr_opacity', 02:32:56 INFO - 'tart', 02:32:56 INFO - 'tscrollx', 02:32:56 INFO - 'cart']}, 02:32:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:56 INFO - 'talos_options': ['--disable-e10s'], 02:32:56 INFO - 'tests': ['tp5o']}, 02:32:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:56 INFO - 'tests': ['tp5o']}, 02:32:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:56 INFO - 'talos_options': ['--disable-e10s', 02:32:56 INFO - '--xperf_path', 02:32:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:32:56 INFO - 'tests': ['tp5n']}, 02:32:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:56 INFO - 'talos_options': ['--xperf_path', 02:32:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:32:56 INFO - 'tests': ['tp5n']}}, 02:32:56 INFO - 'talos.zip': {'path': '', 02:32:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:32:56 INFO - Running post-action listener: _resource_record_post_action 02:32:56 INFO - ##### 02:32:56 INFO - ##### Running create-virtualenv step. 02:32:56 INFO - ##### 02:32:56 INFO - Running pre-action listener: _resource_record_pre_action 02:32:56 INFO - Running main action method: create_virtualenv 02:32:56 INFO - Creating virtualenv /builds/slave/test/build/venv 02:32:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:32:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:32:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:32:56 INFO - Using real prefix '/tools/python27' 02:32:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:32:57 INFO - Installing distribute.............................................................................................................................................................................................done. 02:33:00 INFO - Installing pip.................done. 02:33:00 INFO - Return code: 0 02:33:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:33: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:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33: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:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1014eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccabda5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ce00>, '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 0x1011f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101387b20>, '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.Z7P5OnW4WJ/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.50DGKGbyve/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:33:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:33:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z7P5OnW4WJ/Render', 02:33:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:33:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:33:00 INFO - 'HOME': '/Users/cltbld', 02:33:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:33:00 INFO - 'LOGNAME': 'cltbld', 02:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:00 INFO - 'MOZ_NO_REMOTE': '1', 02:33:00 INFO - 'NO_EM_RESTART': '1', 02:33:00 INFO - 'PAGER': '/bin/cat', 02:33:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:33:00 INFO - 'PWD': '/builds/slave/test', 02:33:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:33:00 INFO - 'SHELL': '/bin/bash', 02:33:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.50DGKGbyve/Listeners', 02:33:00 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 02:33:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:33:00 INFO - 'USER': 'cltbld', 02:33:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:33:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:33:00 INFO - 'XPC_FLAGS': '0x0', 02:33:00 INFO - 'XPC_SERVICE_NAME': '0', 02:33:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:33:00 INFO - Cleaning up... 02:33:00 INFO - Return code: 0 02:33:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:33: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:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33: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:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1014eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccabda5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ce00>, '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 0x1011f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101387b20>, '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.Z7P5OnW4WJ/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.50DGKGbyve/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:33:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:33:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z7P5OnW4WJ/Render', 02:33:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:33:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:33:00 INFO - 'HOME': '/Users/cltbld', 02:33:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:33:00 INFO - 'LOGNAME': 'cltbld', 02:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:00 INFO - 'MOZ_NO_REMOTE': '1', 02:33:00 INFO - 'NO_EM_RESTART': '1', 02:33:00 INFO - 'PAGER': '/bin/cat', 02:33:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:33:00 INFO - 'PWD': '/builds/slave/test', 02:33:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:33:00 INFO - 'SHELL': '/bin/bash', 02:33:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.50DGKGbyve/Listeners', 02:33:00 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 02:33:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:33:00 INFO - 'USER': 'cltbld', 02:33:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:33:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:33:00 INFO - 'XPC_FLAGS': '0x0', 02:33:00 INFO - 'XPC_SERVICE_NAME': '0', 02:33:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:33:01 INFO - Downloading/unpacking psutil>=0.7.1 02:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:33:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:33:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:33:04 INFO - Installing collected packages: psutil 02:33:04 INFO - Running setup.py install for psutil 02:33:04 INFO - building 'psutil._psutil_osx' extension 02:33:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:33:05 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:33:05 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:33:05 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:33:05 INFO - building 'psutil._psutil_posix' extension 02:33:05 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:33:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:33:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:33:05 INFO - ^ 02:33:05 INFO - 1 warning generated. 02:33:05 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:33:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:33:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:33:05 INFO - Successfully installed psutil 02:33:05 INFO - Cleaning up... 02:33:05 INFO - Return code: 0 02:33:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:33: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:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33: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:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1014eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccabda5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ce00>, '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 0x1011f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101387b20>, '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.Z7P5OnW4WJ/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.50DGKGbyve/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:33:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z7P5OnW4WJ/Render', 02:33:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:33:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:33:05 INFO - 'HOME': '/Users/cltbld', 02:33:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:33:05 INFO - 'LOGNAME': 'cltbld', 02:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:05 INFO - 'MOZ_NO_REMOTE': '1', 02:33:05 INFO - 'NO_EM_RESTART': '1', 02:33:05 INFO - 'PAGER': '/bin/cat', 02:33:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:33:05 INFO - 'PWD': '/builds/slave/test', 02:33:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:33:05 INFO - 'SHELL': '/bin/bash', 02:33:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.50DGKGbyve/Listeners', 02:33:05 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 02:33:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:33:05 INFO - 'USER': 'cltbld', 02:33:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:33:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:33:05 INFO - 'XPC_FLAGS': '0x0', 02:33:05 INFO - 'XPC_SERVICE_NAME': '0', 02:33:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:33:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:33:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:33:08 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:33:08 INFO - Installing collected packages: mozsystemmonitor 02:33:08 INFO - Running setup.py install for mozsystemmonitor 02:33:08 INFO - Successfully installed mozsystemmonitor 02:33:08 INFO - Cleaning up... 02:33:08 INFO - Return code: 0 02:33:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1014eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccabda5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ce00>, '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 0x1011f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101387b20>, '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.Z7P5OnW4WJ/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.50DGKGbyve/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:33:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:33:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:33:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z7P5OnW4WJ/Render', 02:33:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:33:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:33:08 INFO - 'HOME': '/Users/cltbld', 02:33:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:33:08 INFO - 'LOGNAME': 'cltbld', 02:33:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:08 INFO - 'MOZ_NO_REMOTE': '1', 02:33:08 INFO - 'NO_EM_RESTART': '1', 02:33:08 INFO - 'PAGER': '/bin/cat', 02:33:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:33:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:33:08 INFO - 'PWD': '/builds/slave/test', 02:33:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:33:08 INFO - 'SHELL': '/bin/bash', 02:33:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.50DGKGbyve/Listeners', 02:33:08 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 02:33:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:33:08 INFO - 'USER': 'cltbld', 02:33:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:33:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:33:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:33:08 INFO - 'XPC_FLAGS': '0x0', 02:33:08 INFO - 'XPC_SERVICE_NAME': '0', 02:33:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:33:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:33:08 INFO - Downloading/unpacking blobuploader==1.2.4 02:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:11 INFO - Downloading blobuploader-1.2.4.tar.gz 02:33:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:33:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:33:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:33:12 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:33:12 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:33:12 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:33:12 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:33:12 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:33:12 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:33:12 INFO - Downloading docopt-0.6.1.tar.gz 02:33:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:33:12 INFO - Installing collected packages: blobuploader, requests, docopt 02:33:12 INFO - Running setup.py install for blobuploader 02:33:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:33:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:33:12 INFO - Running setup.py install for requests 02:33:13 INFO - Running setup.py install for docopt 02:33:13 INFO - Successfully installed blobuploader requests docopt 02:33:13 INFO - Cleaning up... 02:33:13 INFO - Return code: 0 02:33:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:33:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33:13 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 0x1014eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccabda5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ce00>, '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 0x1011f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101387b20>, '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.Z7P5OnW4WJ/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.50DGKGbyve/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:33:13 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:33:13 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:33:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z7P5OnW4WJ/Render', 02:33:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:33:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:33:13 INFO - 'HOME': '/Users/cltbld', 02:33:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:33:13 INFO - 'LOGNAME': 'cltbld', 02:33:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:13 INFO - 'MOZ_NO_REMOTE': '1', 02:33:13 INFO - 'NO_EM_RESTART': '1', 02:33:13 INFO - 'PAGER': '/bin/cat', 02:33:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:33:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:33:13 INFO - 'PWD': '/builds/slave/test', 02:33:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:33:13 INFO - 'SHELL': '/bin/bash', 02:33:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.50DGKGbyve/Listeners', 02:33:13 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 02:33:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:33:13 INFO - 'USER': 'cltbld', 02:33:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:33:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:33:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:33:13 INFO - 'XPC_FLAGS': '0x0', 02:33:13 INFO - 'XPC_SERVICE_NAME': '0', 02:33:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:33:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:33:13 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-3pxlKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:33:13 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-FB0hOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:33:13 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-1ocKMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:33:13 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-XI6lCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:33:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-2QGsfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:33:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-bS5WQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:33:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-UaVTmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:33:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-6X_0zo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:33:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-semWXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:33:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-ZXezsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:33:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-G1KJe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:33:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-0yrx7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:33:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-Sclr7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:33:15 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-bHoRtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:33:15 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-V4N0hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:33:15 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-pKgP6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:33:15 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-a0wggU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:33:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:33:15 INFO - Running setup.py install for manifestparser 02:33:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:33:15 INFO - Running setup.py install for mozcrash 02:33:15 INFO - Running setup.py install for mozdebug 02:33:15 INFO - Running setup.py install for mozdevice 02:33:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:33:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:33:16 INFO - Running setup.py install for mozfile 02:33:16 INFO - Running setup.py install for mozhttpd 02:33:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:33:16 INFO - Running setup.py install for mozinfo 02:33:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:33:16 INFO - Running setup.py install for mozInstall 02:33:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:33:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:33:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:33:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:33:16 INFO - Running setup.py install for mozleak 02:33:16 INFO - Running setup.py install for mozlog 02:33:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:33:16 INFO - Running setup.py install for moznetwork 02:33:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:33:17 INFO - Running setup.py install for mozprocess 02:33:17 INFO - Running setup.py install for mozprofile 02:33:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:33:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:33:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:33:17 INFO - Running setup.py install for mozrunner 02:33:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:33:17 INFO - Running setup.py install for mozscreenshot 02:33:17 INFO - Running setup.py install for moztest 02:33:17 INFO - Running setup.py install for mozversion 02:33:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:33:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:33:17 INFO - Cleaning up... 02:33:17 INFO - Return code: 0 02:33:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:33:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1014eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccabda5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ce00>, '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 0x1011f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101387b20>, '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.Z7P5OnW4WJ/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.50DGKGbyve/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:33:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:33:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z7P5OnW4WJ/Render', 02:33:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:33:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:33:17 INFO - 'HOME': '/Users/cltbld', 02:33:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:33:17 INFO - 'LOGNAME': 'cltbld', 02:33:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:17 INFO - 'MOZ_NO_REMOTE': '1', 02:33:17 INFO - 'NO_EM_RESTART': '1', 02:33:17 INFO - 'PAGER': '/bin/cat', 02:33:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:33:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:33:17 INFO - 'PWD': '/builds/slave/test', 02:33:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:33:17 INFO - 'SHELL': '/bin/bash', 02:33:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.50DGKGbyve/Listeners', 02:33:17 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 02:33:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:33:17 INFO - 'USER': 'cltbld', 02:33:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:33:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:33:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:33:17 INFO - 'XPC_FLAGS': '0x0', 02:33:17 INFO - 'XPC_SERVICE_NAME': '0', 02:33:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:33:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:33:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-VvEbrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:33:18 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:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:33:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-jJmu80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:33:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:33:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-6gMI_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:33:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:33:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-tJ66TB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:33:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:33:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-YYWvTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:33:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:33:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-bdWkGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:33:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:33:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-LwnoNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:33:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:33:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-ybbmau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:33:19 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:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:33:19 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-c_HsS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:33:19 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:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:33:19 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-z86Nri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:33:19 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:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:33:19 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-ok_XEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:33:19 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:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:33:19 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-Uc83c6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:33:19 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-aYQd74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:33:19 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-mo2fft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:33:19 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-paYrPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:33:19 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-EWwUkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:33:19 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-K_yy6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:33:20 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:33:20 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:33:20 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:33:20 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:33:20 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:33:20 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:33:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:33: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:33: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:33: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:33: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:33: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:33: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:33:22 INFO - Downloading blessings-1.6.tar.gz 02:33:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:33:22 INFO - Installing collected packages: blessings 02:33:22 INFO - Running setup.py install for blessings 02:33:22 INFO - Successfully installed blessings 02:33:22 INFO - Cleaning up... 02:33:22 INFO - Return code: 0 02:33:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:33:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:33:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:33:23 INFO - Reading from file tmpfile_stdout 02:33:23 INFO - Current package versions: 02:33:23 INFO - blessings == 1.6 02:33:23 INFO - blobuploader == 1.2.4 02:33:23 INFO - docopt == 0.6.1 02:33:23 INFO - manifestparser == 1.1 02:33:23 INFO - mozInstall == 1.12 02:33:23 INFO - mozcrash == 0.17 02:33:23 INFO - mozdebug == 0.1 02:33:23 INFO - mozdevice == 0.48 02:33:23 INFO - mozfile == 1.2 02:33:23 INFO - mozhttpd == 0.7 02:33:23 INFO - mozinfo == 0.9 02:33:23 INFO - mozleak == 0.1 02:33:23 INFO - mozlog == 3.1 02:33:23 INFO - moznetwork == 0.27 02:33:23 INFO - mozprocess == 0.22 02:33:23 INFO - mozprofile == 0.28 02:33:23 INFO - mozrunner == 6.11 02:33:23 INFO - mozscreenshot == 0.1 02:33:23 INFO - mozsystemmonitor == 0.0 02:33:23 INFO - moztest == 0.7 02:33:23 INFO - mozversion == 1.4 02:33:23 INFO - psutil == 3.1.1 02:33:23 INFO - requests == 1.2.3 02:33:23 INFO - wsgiref == 0.1.2 02:33:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:33:23 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:33:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33:23 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:33:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33:23 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 0x1014eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccabda5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ce00>, '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 0x1011f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101387b20>, '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.Z7P5OnW4WJ/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.50DGKGbyve/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:33:23 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:33:23 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:33:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z7P5OnW4WJ/Render', 02:33:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:33:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:33:23 INFO - 'HOME': '/Users/cltbld', 02:33:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:33:23 INFO - 'LOGNAME': 'cltbld', 02:33:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:23 INFO - 'MOZ_NO_REMOTE': '1', 02:33:23 INFO - 'NO_EM_RESTART': '1', 02:33:23 INFO - 'PAGER': '/bin/cat', 02:33:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:33:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:33:23 INFO - 'PWD': '/builds/slave/test', 02:33:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:33:23 INFO - 'SHELL': '/bin/bash', 02:33:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.50DGKGbyve/Listeners', 02:33:23 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 02:33:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:33:23 INFO - 'USER': 'cltbld', 02:33:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:33:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:33:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:33:23 INFO - 'XPC_FLAGS': '0x0', 02:33:23 INFO - 'XPC_SERVICE_NAME': '0', 02:33:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:33:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:33:23 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:33:23 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:33:23 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:33:23 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:33:23 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:33:23 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:33:23 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:33:23 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:33:23 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:33:23 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:33:23 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:33:23 INFO - Cleaning up... 02:33:23 INFO - Return code: 0 02:33:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:33:23 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:33:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33:23 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:33:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33:23 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 0x1014eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccabda5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ce00>, '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 0x1011f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101387b20>, '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.Z7P5OnW4WJ/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.50DGKGbyve/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:33:23 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:33:23 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:33:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z7P5OnW4WJ/Render', 02:33:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:33:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:33:23 INFO - 'HOME': '/Users/cltbld', 02:33:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:33:23 INFO - 'LOGNAME': 'cltbld', 02:33:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:23 INFO - 'MOZ_NO_REMOTE': '1', 02:33:23 INFO - 'NO_EM_RESTART': '1', 02:33:23 INFO - 'PAGER': '/bin/cat', 02:33:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:33:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:33:23 INFO - 'PWD': '/builds/slave/test', 02:33:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:33:23 INFO - 'SHELL': '/bin/bash', 02:33:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.50DGKGbyve/Listeners', 02:33:23 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 02:33:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:33:23 INFO - 'USER': 'cltbld', 02:33:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:33:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:33:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:33:23 INFO - 'XPC_FLAGS': '0x0', 02:33:23 INFO - 'XPC_SERVICE_NAME': '0', 02:33:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:33:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:33:23 INFO - Downloading/unpacking jsonschema 02:33:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:33:26 INFO - Downloading/unpacking functools32 (from jsonschema) 02:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:26 INFO - Downloading functools32-3.2.3-2.tar.gz 02:33:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:33:26 INFO - warning: no files found matching '*.txt' 02:33:26 INFO - no previously-included directories found matching 'build' 02:33:26 INFO - no previously-included directories found matching 'dist' 02:33:26 INFO - no previously-included directories found matching '.git*' 02:33:26 INFO - Installing collected packages: jsonschema, functools32 02:33:26 INFO - Running setup.py install for functools32 02:33:26 INFO - warning: no files found matching '*.txt' 02:33:26 INFO - no previously-included directories found matching 'build' 02:33:26 INFO - no previously-included directories found matching 'dist' 02:33:26 INFO - no previously-included directories found matching '.git*' 02:33:26 INFO - Successfully installed jsonschema functools32 02:33:26 INFO - Cleaning up... 02:33:26 INFO - Return code: 0 02:33:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:33:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1014eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccabda5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ce00>, '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 0x1011f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101387b20>, '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.Z7P5OnW4WJ/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.50DGKGbyve/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:33:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:33:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z7P5OnW4WJ/Render', 02:33:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:33:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:33:26 INFO - 'HOME': '/Users/cltbld', 02:33:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:33:26 INFO - 'LOGNAME': 'cltbld', 02:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:26 INFO - 'MOZ_NO_REMOTE': '1', 02:33:26 INFO - 'NO_EM_RESTART': '1', 02:33:26 INFO - 'PAGER': '/bin/cat', 02:33:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:33:26 INFO - 'PWD': '/builds/slave/test', 02:33:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:33:26 INFO - 'SHELL': '/bin/bash', 02:33:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.50DGKGbyve/Listeners', 02:33:26 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 02:33:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:33:26 INFO - 'USER': 'cltbld', 02:33:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:33:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:33:26 INFO - 'XPC_FLAGS': '0x0', 02:33:26 INFO - 'XPC_SERVICE_NAME': '0', 02:33:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:33:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:33:27 INFO - Downloading/unpacking flake8 02:33: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:33: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:33: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:33: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:33: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:33: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:33:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:33:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:33:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:33:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:33:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:33:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:33: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:33: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:33: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:33: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:33: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:33: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:33:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:33:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:33:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:33:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:33:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:33:30 INFO - no previously-included directories found matching 'docs/_build' 02:33:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:33:30 INFO - Running setup.py install for pep8 02:33:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:33:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:33:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:33:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:33:30 INFO - no previously-included directories found matching 'docs/_build' 02:33:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:33:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:33:30 INFO - Cleaning up... 02:33:31 INFO - Return code: 0 02:33:31 INFO - Running post-action listener: _resource_record_post_action 02:33:31 INFO - Running post-action listener: _start_resource_monitoring 02:33:31 INFO - Starting resource monitoring. 02:33:31 INFO - ##### 02:33:31 INFO - ##### Running install step. 02:33:31 INFO - ##### 02:33:31 INFO - Running pre-action listener: _resource_record_pre_action 02:33:31 INFO - Running main action method: install 02:33:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:33:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:33:31 INFO - Reading from file tmpfile_stdout 02:33:31 INFO - Detecting whether we're running mozinstall >=1.0... 02:33:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:33:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:33:31 INFO - Reading from file tmpfile_stdout 02:33:31 INFO - Output received: 02:33:31 INFO - Usage: mozinstall [options] installer 02:33:31 INFO - Options: 02:33:31 INFO - -h, --help show this help message and exit 02:33:31 INFO - -d DEST, --destination=DEST 02:33:31 INFO - Directory to install application into. [default: 02:33:31 INFO - "/builds/slave/test"] 02:33:31 INFO - --app=APP Application being installed. [default: firefox] 02:33:31 INFO - mkdir: /builds/slave/test/build/application 02:33:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:33:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application Process Process-1: Traceback (most recent call last): File "/tools/python27/lib/python2.7/multiprocessing/process.py", line 258, in _bootstrap self.run() File "/tools/python27/lib/python2.7/multiprocessing/process.py", line 114, in run self._target(*self._args, **self._kwargs) File "/builds/slave/test/build/venv/lib/python2.7/site-packages/mozsystemmonitor/resourcemonitor.py", line 47, in _collect io = psutil.disk_io_counters() File "/builds/slave/test/build/venv/lib/python2.7/site-packages/psutil/__init__.py", line 1673, in disk_io_counters rawdict = _psplatform.disk_io_counters() RuntimeError: unable to get the disk's parent. 02:34:03 INFO - Reading from file tmpfile_stdout 02:34:03 INFO - Output received: 02:34:03 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:34:03 INFO - Running post-action listener: _resource_record_post_action 02:34:03 INFO - ##### 02:34:03 INFO - ##### Running run-tests step. 02:34:03 INFO - ##### 02:34:03 INFO - Running pre-action listener: _resource_record_pre_action 02:34:03 INFO - Running main action method: run_tests 02:34:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:34:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:34:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:34:03 INFO - 2016-06-06 02:34:03.102 screenresolution[1645:8117] starting screenresolution argv=screenresolution get 02:34:03 INFO - 2016-06-06 02:34:03.104 screenresolution[1645:8117] Display 0: 1600x1200x32@60 02:34:03 INFO - 2016-06-06 02:34:03.110 screenresolution[1646:8120] starting screenresolution argv=screenresolution list 02:34:03 INFO - Available Modes on Display 0 02:34:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:34:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:34:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:34:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:34:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:34:03 INFO - Intel Iris: 02:34:03 INFO - Chipset Model: Intel Iris 02:34:03 INFO - Type: GPU 02:34:03 INFO - Bus: Built-In 02:34:03 INFO - VRAM (Dynamic, Max): 1536 MB 02:34:03 INFO - Vendor: Intel (0x8086) 02:34:03 INFO - Device ID: 0x0a2e 02:34:03 INFO - Revision ID: 0x0009 02:34:03 INFO - Displays: 02:34:03 INFO - Display: 02:34:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:34:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:34:03 INFO - Display Serial Number: 1600x1200 60 02:34:03 INFO - Main Display: Yes 02:34:03 INFO - Mirror: Off 02:34:03 INFO - Online: Yes 02:34:03 INFO - Rotation: Supported 02:34:03 INFO - Return code: 0 02:34:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:34:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:34:03 INFO - Python 2.7.3 02:34:03 INFO - Return code: 0 02:34:03 INFO - grabbing minidump binary from tooltool 02:34:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:34:03 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 0x10138ce00>, '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 0x1011f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101387b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:34:03 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:34:03 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:34:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:34:03 INFO - Return code: 0 02:34:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:34:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:34:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:34:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:34:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:34:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:34:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:34:04 INFO - Return code: 0 02:34:04 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/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0310', '--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:34:04 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/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0310 --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:34:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z7P5OnW4WJ/Render', 02:34:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:34:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:34:04 INFO - 'HOME': '/Users/cltbld', 02:34:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:34:04 INFO - 'LOGNAME': 'cltbld', 02:34:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:34:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:34:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:34:04 INFO - 'MOZ_NO_REMOTE': '1', 02:34:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:34:04 INFO - 'NO_EM_RESTART': '1', 02:34:04 INFO - 'PAGER': '/bin/cat', 02:34:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:34:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:34:04 INFO - 'PWD': '/builds/slave/test', 02:34:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:34:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:34:04 INFO - 'SHELL': '/bin/bash', 02:34:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.50DGKGbyve/Listeners', 02:34:04 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 02:34:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:34:04 INFO - 'USER': 'cltbld', 02:34:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:34:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:34:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:34:04 INFO - 'XPC_FLAGS': '0x0', 02:34:04 INFO - 'XPC_SERVICE_NAME': '0', 02:34:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:34:04 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/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0310', '--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:34:04 INFO - mozversion application_buildid: 20160606003529 02:34:04 INFO - mozversion application_changeset: 7f8d31a531ca31d5206f0a37528c36d1695ad767 02:34:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:34:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:34:04 INFO - mozversion application_name: Firefox 02:34:04 INFO - mozversion application_remotingname: firefox-dev 02:34:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:34:04 INFO - mozversion application_vendor: Mozilla 02:34:04 INFO - mozversion application_version: 48.0a2 02:34:04 INFO - mozversion platform_buildid: 20160606003529 02:34:04 INFO - mozversion platform_changeset: 7f8d31a531ca31d5206f0a37528c36d1695ad767 02:34:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:34:04 INFO - mozversion platform_version: 48.0a2 02:34:04 INFO - using testdate: 1465205644 02:34:04 INFO - actual date: 1465205644 02:34:04 INFO - starting webserver on 'localhost:49278' 02:34:04 INFO - SUITE-START | Running 2 tests 02:34:04 INFO - TEST-START | dromaeo_css 02:34:04 INFO - operating with platform_type : mac_ 02:34:04 INFO - Initialising browser for dromaeo_css test... 02:34:04 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmpTTuP8U/profile http://localhost:49278/getInfo.html) 02:34:07 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 02:34:07 INFO - PROCESS | 1664 | colorDepth:24 02:34:07 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 02:34:07 INFO - PROCESS | 1664 | __metrics 02:34:07 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 02:34:08 INFO - TEST-INFO | 1664: exit 0 02:34:08 INFO - Browser initialized. 02:34:08 INFO - Running cycle 1/1 for dromaeo_css test... 02:34:08 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmpTTuP8U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:34:20 INFO - PROCESS | 1668 | RSS: Main: 221036544 02:34:20 INFO - PROCESS | 1668 | 02:35:09 INFO - PROCESS | 1668 | 1465205709733 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:37:20 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 02:37:22 INFO - PROCESS | 1668 | RSS: Main: 232566784 02:37:22 INFO - PROCESS | 1668 | 02:40:24 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 02:40:24 INFO - PROCESS | 1668 | RSS: Main: 243138560 02:40:24 INFO - PROCESS | 1668 | 02:43:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 02:43:27 INFO - PROCESS | 1668 | RSS: Main: 245182464 02:43:27 INFO - PROCESS | 1668 | 02:46:28 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 02:46:29 INFO - PROCESS | 1668 | RSS: Main: 247623680 02:46:29 INFO - PROCESS | 1668 | 02:49:32 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 02:49:32 INFO - PROCESS | 1668 | RSS: Main: 249708544 02:49:32 INFO - PROCESS | 1668 | 02:52:35 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 02:52:35 INFO - PROCESS | 1668 | RSS: Main: 251412480 02:52:35 INFO - PROCESS | 1668 | 02:52:35 INFO - PROCESS | 1668 | __start_tp_report 02:52:35 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 02:52:35 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 02:52:35 INFO - PROCESS | 1668 | |i|pagename|runs| 02:52:35 INFO - PROCESS | 1668 | |0;dojo.html;3411.505331726901;3412.948805767914;3433.9484907726387;3454.3954807908617;3460.619331873495;5662.178984047316;5693.8576535586635;5698.088305871067;5701.885962280752;5734.827955161347;5641.407652196522;5672.943270567293;5676.914846277309;5703.5722320826;5704.971475142629;5119;5129.358830146231;5168.715720635382;5201.219817027439;5215.478452154774;5030.723310217936;5179.989901969117;5222.138347172712;5277.8944421111555;5282.550983166417;5179.87050323743;5194.818181363653;5197.974010129963;5206.401263854667;5218.452062533436;4128.339465685506;5169.431362550116;5170.457102004273;5185.299984502081;5204.869878253055;5009.624278179152;5157.613179011589;5158.7678554464765;5184.585233181337;5210.947890521084;5624.072028115356;5643.068893632546;5702.942970570324;5712.542996560265;5733.226014488033;6002.189704389895;6162.907556386658;6164.044573091178;6181.96909015452;6213.751449941997;5320.733963301858;6197.814065577994;6279.497640188774;6303.810885673437;6319.399657032574;5792.536597072224;5797.333306669745;5816;5836.5622578306375;5840.620359676693;6214.627122372672;6231.937680623227;6251;6302.149209856702;6302.306746257908;6081.695915204222;6107.144999700045;6109.2974307955155;6135.263768347827;6153.876922461525;6074.605150665193;6225.470834979077;6226.221722284715;6232.065190221503;6235.96882015587;6068.938035465215;6123.9232566596775;6136.917362303726;6194.38056194386;6199.070139479114;6171.228596425447;6175.2589689237575;6184.072389141665;6210.937890621036;6230.968845155836;4795.184818580849;4817.205161148441;4836.056968891033;4888.144574699413;4921.311016457699;3765.246950609889;4323.481182258086;4350.369196466495;4387.27609944362;4390.05613793036;5729.312482502127;5741.196232527366;5767.798127065533;5771.192033115367;5789.652620842763;5211.687298762086;5731;5784.334801497882;6253.280872699606;6274.278457977391;5739.62692424991;5779.930712818142;5788.160716696139;5794.942050579524;5831.562632802556;4786.593139583105;5288.127458969227;5375.892482150413;5392.110301800238;5425.050616142159;6188.9690551546955;6219.191505104308;6235.781747638811;6258.092576576462;6337.7781777637565;6239.656818875005;6254.687265636609;6257.843553911189;6262.248530176408;6287.245530536182;6300.054991751274;6310.873782524232;6331.113644089924;6333.556651034383;6340.556161068866;5996.400359964143;6134.069235337226;6137.407629318811;6156.322804491592;6204.441600255998;6217.378262173746;6237.656928868774;6260.060990851409;6264.968675156777;6285.905711414241;5968.880622387441;6047.818565443044;6130.01919692847;6149.877002460015;6175.876482470415;5353.625246232727;5952.136940143569;5993.101034844808;6000.159977603052;6044.879102418015;5182.404023537341;5867.061270196748;5885.499732522612;5906.114082887601;5913.231279933581;5099.592032637306;6071.605345652695;6073.089036644538;6076.696165191811;6094.238220222473;4954.801807927643;4993.775280112331;5937.7921772737745;5986.07215881539;6014.398560143951 02:52:35 INFO - PROCESS | 1668 | |1;ext.html;495.21013982697593;510.14550627698594;515.7704821354499;539.4039586257186;552.1028328965432;38392;39996.200075998466;40011;40109;40158.59841401589;41319;42019;42062.579374206245;42063;42230.78884605573;9375.812483750338;9532.571034303439;9563.665271715492;9634.9518252409;9643.662471813488;9516.762080947998;9758.804823903552;9781.070798274153;9785.608575656965;9803.803923921518;9753.561089750978;9760.316777825556;9802.2158227342;9817;9824.066713662227;9710.514474276293;9777.266704997153;9780.804383912282;9797.559109840075;9817.754556136153;9691;9777.706668799947;9818.705438836774;9822.459764712938;9864.112229899343;40138.598614013776;40881.79559102185;40923.7953810229;40951.7952410236;40969;3173.1432513221303;3732.9440058399146;3802.885913422602;3879.7866117363533;3894.9610503895165;28492.572611410844;29357.85321073402;29368.706312937025;29420.705792942226;29658.70341296581;2728.8499132547704;2776.763975062102;2813.1419916925533;2831.985840070807;2845.7154284571584;472657;475513;475907;478425;480613;64940;65438.01842972359;66038.33961660418;66113.6694316535;66306.66846665736;70504.64747676332;72798.63600681962;73491.26508734844;74786.6260668704;74910.87633685498;13905.582832515041;17572.38496652611;17704.734428983575;17717.7342339865;17811.643767124584;13298;13362.131461454974;13375.665608359868;13385.665358365924;13419.194848309133;17193.312267509158;20366.694499582518;20583.176672933216;20601;20637.864917429684;7120.893186602205;8108.5945702714625;8122.131909660811;8192.795180120545;8213.630386632616;985.3792110970043;1035.699647102347;1079.3631757263252;1112.6161474291357;1116.6594188772503;30377.24056898551;30391.544126838115;30905.918292859642;31012.22469438282;31459.52810707841;1095.2497539185683;1126.5212284778936;1127.301073334538;1135.6666600716214;1224.6264889208699;12752.298623575607;15932.442364517417;15965.920170399306;16021.59946001336;16285.755713664304;633423;635788;641504;643916;647758;61093;63530;63682.72634547283;63724.681376592824;63799;1493.4474244529595;1500.1374209829391;1509.1548732709718;1511.0857930951615;1517.355124072287;30287.697123028487;30654.386912262074;31082.222944425666;31100.844495777375;31191.532127018567;54761.45238547563;56462;56585.71707141438;56695.716521417125;56917.43082569121;602222;602961;603120;604610;604991;28106.73519691578;28279.71720282853;28280.43439131247;28348.724307405802;28734.281642959093;45493.31760023668;45741;48452.03095938132;48885;49943.250851237986;38841.6115838838;40154.9961250971;40353;40416.68332866802;40421.797891010356;39188.02029949274;39198.216035679696;39374.80312598419;39575.60424395835;39766.403503948044;38245.80877095597;38352.42471362876;38566.80716596399;38884.2223155541;39441.802790985865;32613;33425.83287083549;33755.493667594514;33919;34313.31373372469;21356.7864321361;25407.110751123622;25522.744772552778;25592.616110758725;25817.870910646077 02:52:35 INFO - PROCESS | 1668 | |2;jquery.html;369.5196244881654;396.0870194202366;400.5293779808724;400.78362170811585;406.6685651194276;12797.360131993386;15576;20132.59734805305;20734.963251837424;20856.374308770723;19948;20219.191232350688;20345.28791492298;20696.68954965673;20711.792882071175;5120.4367519572725;5973.22348094747;6005.099235114746;6044.24446944132;6061.272647282311;4968.2795994580765;5620.606557540974;5862.824115276549;5939.07944268639;5941.316748573904;5089.3638295213095;5787.855303617423;5796.797112101077;5911.970440147815;5919.17131601574;4782.019685964369;5868.647881127104;5996.310424301174;6051.394860513936;6083.634981901078;5436.646617969819;5763.855903602402;5811.070228763419;5956.106584012389;6011.947909115931;11607.6517704469;13504.594862154152;13611.11527750693;19901.502462438555;24243.90902409396;2176.1621775616345;2223.2218723446827;2244.730632324115;2640.0633854402795;2682.4903268378944;9966.302358834884;9980.850287245697;10124.98750124989;10133.543990520444;10240.436775977318;1743.1458585293242;1746.2142036083685;1746.8689848261304;1787.347618119377;1809.782826060868;76310;111084;116069.83930160674;117281;119361.40319298435;11724.58963936258;11785.3518056506;11994.280343179436;12116.15186936906;12116.636500904988;15184.01303915242;15227.543173704807;15249.085054896741;15285.006474579117;15297.847021529866;8864.335174861782;8865.980412252673;8909.821803564022;8983.191512763882;8983.505907175168;7807;8810.295176385875;8868.113188681209;8894.86657700135;8975.967763707127;4625.421822272216;4715.834945776881;4716.9056618867435;4727.881802954885;4767.475577686451;1965.4005528313717;2040.3776848061484;2042.0810635213916;2080.833533317331;2097.7587577428485;899.5097671768901;952.2524818017823;998.3311181508407;1015.9034891685278;1026.2149455666422;16662.83337166637;18702.906485467756;19880;21013.369598912057;21097.261595844073;863.5509535041743;971.5093877591771;991.5538007896475;1000.5297510170209;1004.8593196952432;10262.897371026344;10522.328548644216;11111.83332250017;11121.27711698737;11191.216614836883;517632;659381;665068;676125;679045;90404.54797726101;106494;117148.24277635843;124496;136320;975.5902520941222;999.3604093380243;1001.5242759689089;1005.2812239248972;1035.7928414317016;17900.91049544796;18008.729869051713;18079.45761627153;18222.72665910039;18521;51453.48546514637;51806.74096629493;51936.62411943212;54343.91312173655;54503.90992180214;76594.61702691451;77870.6106469464;79888.60055699684;81133.59433202796;81624.18375816403;7941.6029198541;8156.306713929145;8309.961254843145;8335.208155225244;8362.45644033136;9032.548372581476;11831.822522661994;12406;12804.423800928795;12930;10758.5973873683;10810.35137891729;10877.945610272214;11013.614523491638;11014.889851101387;8785.385023048326;10386.688399348031;10610;10665.200109991627;11092.667219983414;10688.57245710194;10980.396078215455;11232.494537745657;11530.1928864982;11536.307821530734;7741.380689544935;8185.38609604294;8281.54451505149;8287.461315014745;8507.489550626982;9763.85354219673;11083.279586826831;11223.71940701489;11422.600208992646;11453.25553839031 02:52:35 INFO - PROCESS | 1668 | |3;mootools.html;1597.6884507521033;1617.2964760329257;1853.7126745354467;1873.7376767252588;1995.3814317561548;7949.92050079499;8663;8837.248833849122;9060.864087038692;9077.909220907797;8781.780455488615;8782.604782784774;8872.822543549126;8903.95548022259;8936.955315223431;2478.293686299405;2495.413577809213;2501.7998560115147;2503.486785209037;2513.1204078572537;2445.2419749877504;2487.2911220302126;2503.4492411669494;2510.7363726808694;2514.497100579882;1994.8005199480008;2116.8200702940267;2265.252466685998;2507.749225077496;2723.332783468043;2227.7438094619083;2285.474340901602;2479.0331770609478;2498.150628786212;2513.158092039169;2201.7027701260445;2424.066734307287;2487.315988103268;2502.461970676295;2516.6728325317777;8980.967188773244;9018.36871419001;9088.454692718458;9092.04533523977;9102.27181825459;5515.255440515519;5652.971735141339;5706.315242170925;5722.37053924068;5773.711314434304;3023.4104349651825;3030.2878823476467;3314.6022477302877;3519.1729943463265;3574.2136729919375;1064.7178497698117;1089.8910108989116;1089.9509522071526;1090.2749671468464;1094.141099237095;12120.212186207867;12137.332446715427;12309.876901230962;12311.876881231252;12341.876581234163;4927.334809800703;4929.852104436873;5031.471695471922;5050.065737838511;5096.566791822736;8889.4666320021;10455.477226138662;10470.947645261725;10482.63310784119;10495;5536.723163841792;5555.055640541118;5565;5591.776328946797;5607.990561698934;4457.821687132543;4834.927476087862;4917.803287868445;5002.874928126826;5037.345145131109;3987.444896490371;4010.7192496524963;4081.77550234734;4192.706510544293;4218.325067989107;1368.7194125204508;1374.2304309586464;1374.7662897307482;1377.373295150704;1382.8893688504895;814.5479259011306;886.3485338276362;957.9521023948774;963.3786207895868;966.5505539923969;6026.427489388501;6070.544709146831;6146.7233974471255;6214.875702485925;6225.3774622537385;910.2399496420563;1035.7514196592763;1039.1219419590584;1044.4307852220559;1107.9058280046286;3944.723869329119;3981.7212795104065;4042.2319759245647;4045.474088368493;4060.3300455424524;10804.351738895692;11090.5563777448;11099.556017759362;11125.666230013112;11129.666110016713;10818.972197641211;10940.56237750497;11015.228933974706;11018.39398833072;11087.556497740161;6561.770338038145;6831.941049137392;8023.318017968538;8543.487390756574;8582.313414926666;5039.344885165052;5655.406182350794;5921.851953701192;5944.4352786485215;5949.405059494016;8057.717979870677;9413.81172376535;9663;9728;9765.121139097515;8146.511209327459;8385.916140838757;9726.562304696165;9921.851172232567;9948.403095814274;6916.619585922823;7406.185319614946;7575.393968482618;7674.079110506775;7722.382209423345;7112.822179445555;8375.539345336065;8386.538740369338;8424.915750842412;8459.365547583833;7392.334689877936;7581.355584775135;7596.544207347577;7638.588160337644;7778.49439786412;7431.70273189088;7543.358814500829;7544.132424771222;7654.73208437702;7683.270089341504;7096.361327480551;7125.501214914906;7127.607981561064;7161.069061022034;7165.068541089625;4889.486603906681;4951.04839273071;5067.695938243717;5077.136886729191;5121.359830021247;5087.364079490064;5099.87250318745;5112.821051263337;5181.766820493011;5350.705711185922 02:52:35 INFO - PROCESS | 1668 | |4;prototype.html;325.1254126399984;325.54098720803654;331.5656489998103;333.76136062715153;339.33490358896563;6775.1531058617675;6792.388685018347;6812.386885180339;6842.213145488264;6860.656967151647;6755.9662201688925;6764.222114456839;6788.626625535593;6795.524313298071;6810.662775240122;3884;4753.714777113367;4892.486288939664;4914.238293064581;4931.7040977541465;4329.415528903605;4559.612432943204;4906.656534042619;4908.190148625473;4921.8277360292395;4849.151398505265;4861.416630004387;4865.294532292815;4927.728974906378;4936.851894443154;4904.558589726906;4922.753862306906;4934.1365261079345;4946.925796113062;4949.579285760697;4216.430781844442;4223.472065991751;4229.217594744951;4231;4235.428217190702;5577.832665020056;6146.35463276354;6471.05868239531;6486.805395838085;6496.935030649727;5756.338021127582;5787.13193021046;5807.709614519299;5820.476157145878;5823.039198532237;7171.748988785367;7190.892136617955;7195;7226.313500217471;7227.084822522036;4635.420572428447;4636.79134438951;4641;4646.79089440976;4671.813127474897;7501.812454688622;7611.771646850603;7614.885776713354;7625.389968802538;7637.923620763721;4524.321351797191;4666.230072038144;4676.883077923079;4683.133620280191;4695.765211739399;4743.5967942724565;4816.133096042746;4824.962633033914;4830.951690483121;4834.975825120852;5767.653940763567;5771.336296325892;5782.132680097935;5793.565482588822;5801.9129713054335;6236.345183755731;7206.351428371472;7210.206877243604;7213.89179162313;7217.953396757546;1659.311385774914;1672.5567724553002;1673.8785014040623;1675.1289329548567;1679.7984241891052;1012.9848052279222;1047.5700668587324;1057.3338796558119;1061.9734506637242;1069.978600427987;5539.473749993744;6770.052192693027;6795.286494918061;6804.387605115563;6808.489363297674;6845.931540684529;6921.8961715574305;7296.306850849161;7545.849083018309;7564.583947882919;6656.467482601381;6818.522703410714;6838;6846.9729540569315;6854.725810967506;3376.716847597946;3881.8059097045034;3945.2306800174265;3997.960020399817;4003.9990002499376;7905.56519391439;7925.8811117833275;7926.445148839526;7935.603219838985;7959.08470525897;6093.634381937098;7722.7217970698775;7820;7841.882371764428;7849.686012559548;7891.605419729105;7926.801829954182;7942.563159026309;7971.44199906001;7975.8404831903035;7560.092788865371;7586.924130758622;7588.392928565812;7594.924050759421;7596.658150383357;6078.331383547895;6079.270487541523;6516.087747715418;7124.323189297009;7453.776386708408;5976.043832986701;6377.585456945284;6833.41915937131;7682.692692292246;7919.049714034353;4686.226772582486;4738.952610473852;4741.620670346295;4758.5241475852135;4770.356001939694;7419.962900185465;7493.850122997618;7520.8871866920945;7529.171791103084;7588.734394296173;6044.486218671462;6456.612603243821;7073.893891591527;7261.237570055068;7270.091238595176;6970.546914450544;7172;7251.274872512707;7262.49162558637;7263.963680181565;7000.47496437759;7062.046623705939;7075.858482830417;7078.1506219254015;7082.433405327664;7183.748568800067;7214.855702885808;7227.710891564278;7227.819304517218;7254.600996945219;7080.645967701491;7080.752173673896;7096.964515177391;7103.893441598483;7141.857162856818 02:52:35 INFO - PROCESS | 1668 | |5;yui.html;687.2955220898582;714.2500374606664;725.5392825555774;743.5352904434728;760.3574979142625;14735.631609209775;14891.106533607965;14989.325480353384;15015.099094054365;15126.319315630795;13706.725865482684;13735.793963090562;13740.519081832137;13761.587152385444;13764.793528097087;734.4895297768053;913.4062859141545;923.2660035271941;962.0716009051264;965.4882912056621;935.2424536125726;955.0831202046044;962.687126683827;969.6896992962255;971.2375785008769;871.4727589808169;939.3940908114262;940.7554035950668;969.5976169889487;1003.8142943555465;947.4362754161332;983.2969426859794;995.5271246158089;1003.1924300937742;1010.1111022300365;698.3505340033767;732.4543215304632;735.871222536054;752.9698812047511;753.3069575990103;14546.127232366092;14627.63430914225;14657.267136643126;14667.266636668124;14738.410463581444;12615.053870959715;12618.55835045769;12737.681557961032;12823.935880320632;13018.088733788638;9802.166815820592;9913.256505762096;9920.40477571348;10225.590976360936;10304.896951030469;3495.4931534927546;3523.859045638171;3548.201654627714;3641.308151451216;3776.131489757371;77015;78094;79065.60467197685;79743.20256797416;79761.80357294645;9578.377405468622;9583.474770871095;9672.22622190223;9756.414615123116;9793.020697930293;21399.786002140092;21734.78265217359;21827.126914923225;21835.454113646967;21837.235696750857;7585.544867307979;7633.274838890296;7776.144624091346;7891.447598668038;7984.361251099898;4106.035081755785;4138.441310423054;4182.309918863414;4192.81132349045;4345.608895199384;3549.4675798629896;3551.3785087609567;3578.6600272974024;3620.728445366608;3645;651.8663673946925;672.4989882537443;674.6694119881223;677.0183234310269;680.7106979533777;583.4661284924332;585.3561082808961;589.840962508664;590.6721769418015;601.128363872378;8188.672453101928;8234.752957411287;8294.626741796634;8462.746117616482;8587.65649374018;593.5370411079365;602.6625089949642;604.7762327938691;622.9626222426668;641.0608458608161;3631.3504927548174;3842.4773474061426;3903.160820423566;3921.490206273223;3979.7413168143976;90071.54964225137;91336.543317283;91874.54062729643;91925.08074919165;95861.04138958521;77257;78310;81319.78020329581;82134.3573128534;82347;69752;70630;70681.23296917618;71355.2864471359;73142.53714925672;8469.280111190617;8475.237228649452;8512;8514.446560973516;8820.5589720515;48759;51356;51453.22820157626;51794.48205518047;51942.74028629833;65489.345106548324;66343;66751;73512.63243683748;74330.62834686009;10695.465226738788;11112.333260004103;11251.099912006855;11301.943490282825;11454.599089031844;10149.49252537385;11855.992240659296;12000.759984800081;12416.192947458387;12553.556341020534;11302.196596373014;11452.797456267304;11455.369954652575;11471.369074700971;11715.297082175097;11494.770104598028;11501.137414693763;11522.711932201426;11727.882721172678;11742.295462272292;10703.678889633324;11230.21388502797;11311.660650180507;11443.198976071595;11604.825927611262;9551.188149007412;9684;9748.951255243963;9828.361156524803;9843.507824608883;9623.518824058629;9642.903570964201;9787.070228328297;9813.803723925625;9866.950665246628 02:52:35 INFO - PROCESS | 1668 | __end_tp_report 02:52:35 INFO - PROCESS | 1668 | __start_cc_report 02:52:35 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,416 02:52:35 INFO - PROCESS | 1668 | __end_cc_report 02:52:35 INFO - PROCESS | 1668 | __startTimestamp1465206755270__endTimestamp 02:52:35 INFO - PROCESS | 1668 | 02:52:35 INFO - PROCESS | 1668 | ------- Summary: start ------- 02:52:35 INFO - PROCESS | 1668 | Number of tests: 6 02:52:35 INFO - PROCESS | 1668 | 02:52:35 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5674.84 Median:5836.56 stddev:659.47 (11.3%) stddev-sans-first:637.14 02:52:35 INFO - PROCESS | 1668 | Values: 3411.5 3412.9 3433.9 3454.4 3460.6 5662.2 5693.9 5698.1 5701.9 5734.8 5641.4 5672.9 5676.9 5703.6 5705.0 5119.0 5129.4 5168.7 5201.2 5215.5 5030.7 5180.0 5222.1 5277.9 5282.6 5179.9 5194.8 5198.0 5206.4 5218.5 4128.3 5169.4 5170.5 5185.3 5204.9 5009.6 5157.6 5158.8 5184.6 5210.9 5624.1 5643.1 5702.9 5712.5 5733.2 6002.2 6162.9 6164.0 6182.0 6213.8 5320.7 6197.8 6279.5 6303.8 6319.4 5792.5 5797.3 5816.0 5836.6 5840.6 6214.6 6231.9 6251.0 6302.1 6302.3 6081.7 6107.1 6109.3 6135.3 6153.9 6074.6 6225.5 6226.2 6232.1 6236.0 6068.9 6123.9 6136.9 6194.4 6199.1 6171.2 6175.3 6184.1 6210.9 6231.0 4795.2 4817.2 4836.1 4888.1 4921.3 3765.2 4323.5 4350.4 4387.3 4390.1 5729.3 5741.2 5767.8 5771.2 5789.7 5211.7 5731.0 5784.3 6253.3 6274.3 5739.6 5779.9 5788.2 5794.9 5831.6 4786.6 5288.1 5375.9 5392.1 5425.1 6189.0 6219.2 6235.8 6258.1 6337.8 6239.7 6254.7 6257.8 6262.2 6287.2 6300.1 6310.9 6331.1 6333.6 6340.6 5996.4 6134.1 6137.4 6156.3 6204.4 6217.4 6237.7 6260.1 6265.0 6285.9 5968.9 6047.8 6130.0 6149.9 6175.9 5353.6 5952.1 5993.1 6000.2 6044.9 5182.4 5867.1 5885.5 5906.1 5913.2 5099.6 6071.6 6073.1 6076.7 6094.2 4954.8 4993.8 5937.8 5986.1 6014.4 02:52:35 INFO - PROCESS | 1668 | 02:52:35 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:71612.62 Median:40156.80 stddev:154348.10 (384.4%) stddev-sans-first:154688.73 02:52:35 INFO - PROCESS | 1668 | Values: 495.2 510.1 515.8 539.4 552.1 38392.0 39996.2 40011.0 40109.0 40158.6 41319.0 42019.0 42062.6 42063.0 42230.8 9375.8 9532.6 9563.7 9635.0 9643.7 9516.8 9758.8 9781.1 9785.6 9803.8 9753.6 9760.3 9802.2 9817.0 9824.1 9710.5 9777.3 9780.8 9797.6 9817.8 9691.0 9777.7 9818.7 9822.5 9864.1 40138.6 40881.8 40923.8 40951.8 40969.0 3173.1 3732.9 3802.9 3879.8 3895.0 28492.6 29357.9 29368.7 29420.7 29658.7 2728.8 2776.8 2813.1 2832.0 2845.7 472657.0 475513.0 475907.0 478425.0 480613.0 64940.0 65438.0 66038.3 66113.7 66306.7 70504.6 72798.6 73491.3 74786.6 74910.9 13905.6 17572.4 17704.7 17717.7 17811.6 13298.0 13362.1 13375.7 13385.7 13419.2 17193.3 20366.7 20583.2 20601.0 20637.9 7120.9 8108.6 8122.1 8192.8 8213.6 985.4 1035.7 1079.4 1112.6 1116.7 30377.2 30391.5 30905.9 31012.2 31459.5 1095.2 1126.5 1127.3 1135.7 1224.6 12752.3 15932.4 15965.9 16021.6 16285.8 633423.0 635788.0 641504.0 643916.0 647758.0 61093.0 63530.0 63682.7 63724.7 63799.0 1493.4 1500.1 1509.2 1511.1 1517.4 30287.7 30654.4 31082.2 31100.8 31191.5 54761.5 56462.0 56585.7 56695.7 56917.4 602222.0 602961.0 603120.0 604610.0 604991.0 28106.7 28279.7 28280.4 28348.7 28734.3 45493.3 45741.0 48452.0 48885.0 49943.3 38841.6 40155.0 40353.0 40416.7 40421.8 39188.0 39198.2 39374.8 39575.6 39766.4 38245.8 38352.4 38566.8 38884.2 39441.8 32613.0 33425.8 33755.5 33919.0 34313.3 21356.8 25407.1 25522.7 25592.6 25817.9 02:52:35 INFO - PROCESS | 1668 | 02:52:35 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:35211.63 Median:2233.98 stddev:106490.27 (4766.8%) stddev-sans-first:106756.87 02:52:35 INFO - PROCESS | 1668 | Values: 369.5 396.1 400.5 400.8 406.7 12797.4 15576.0 20132.6 20735.0 20856.4 19948.0 20219.2 20345.3 20696.7 20711.8 5120.4 5973.2 6005.1 6044.2 6061.3 4968.3 5620.6 5862.8 5939.1 5941.3 5089.4 5787.9 5796.8 5912.0 5919.2 4782.0 5868.6 5996.3 6051.4 6083.6 5436.6 5763.9 5811.1 5956.1 6011.9 11607.7 13504.6 13611.1 19901.5 24243.9 2176.2 2223.2 2244.7 2640.1 2682.5 9966.3 9980.9 10125.0 10133.5 10240.4 1743.1 1746.2 1746.9 1787.3 1809.8 76310.0 111084.0 116069.8 117281.0 119361.4 11724.6 11785.4 11994.3 12116.2 12116.6 15184.0 15227.5 15249.1 15285.0 15297.8 8864.3 8866.0 8909.8 8983.2 8983.5 7807.0 8810.3 8868.1 8894.9 8976.0 4625.4 4715.8 4716.9 4727.9 4767.5 1965.4 2040.4 2042.1 2080.8 2097.8 899.5 952.3 998.3 1015.9 1026.2 16662.8 18702.9 19880.0 21013.4 21097.3 863.6 971.5 991.6 1000.5 1004.9 10262.9 10522.3 11111.8 11121.3 11191.2 517632.0 659381.0 665068.0 676125.0 679045.0 90404.5 106494.0 117148.2 124496.0 136320.0 975.6 999.4 1001.5 1005.3 1035.8 17900.9 18008.7 18079.5 18222.7 18521.0 51453.5 51806.7 51936.6 54343.9 54503.9 76594.6 77870.6 79888.6 81133.6 81624.2 7941.6 8156.3 8310.0 8335.2 8362.5 9032.5 11831.8 12406.0 12804.4 12930.0 10758.6 10810.4 10877.9 11013.6 11014.9 8785.4 10386.7 10610.0 10665.2 11092.7 10688.6 10980.4 11232.5 11530.2 11536.3 7741.4 8185.4 8281.5 8287.5 8507.5 9763.9 11083.3 11223.7 11422.6 11453.3 02:52:35 INFO - PROCESS | 1668 | 02:52:35 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5780.61 Median:5072.42 stddev:3202.72 (63.1%) stddev-sans-first:3196.28 02:52:35 INFO - PROCESS | 1668 | Values: 1597.7 1617.3 1853.7 1873.7 1995.4 7949.9 8663.0 8837.2 9060.9 9077.9 8781.8 8782.6 8872.8 8904.0 8937.0 2478.3 2495.4 2501.8 2503.5 2513.1 2445.2 2487.3 2503.4 2510.7 2514.5 1994.8 2116.8 2265.3 2507.7 2723.3 2227.7 2285.5 2479.0 2498.2 2513.2 2201.7 2424.1 2487.3 2502.5 2516.7 8981.0 9018.4 9088.5 9092.0 9102.3 5515.3 5653.0 5706.3 5722.4 5773.7 3023.4 3030.3 3314.6 3519.2 3574.2 1064.7 1089.9 1090.0 1090.3 1094.1 12120.2 12137.3 12309.9 12311.9 12341.9 4927.3 4929.9 5031.5 5050.1 5096.6 8889.5 10455.5 10470.9 10482.6 10495.0 5536.7 5555.1 5565.0 5591.8 5608.0 4457.8 4834.9 4917.8 5002.9 5037.3 3987.4 4010.7 4081.8 4192.7 4218.3 1368.7 1374.2 1374.8 1377.4 1382.9 814.5 886.3 958.0 963.4 966.6 6026.4 6070.5 6146.7 6214.9 6225.4 910.2 1035.8 1039.1 1044.4 1107.9 3944.7 3981.7 4042.2 4045.5 4060.3 10804.4 11090.6 11099.6 11125.7 11129.7 10819.0 10940.6 11015.2 11018.4 11087.6 6561.8 6831.9 8023.3 8543.5 8582.3 5039.3 5655.4 5921.9 5944.4 5949.4 8057.7 9413.8 9663.0 9728.0 9765.1 8146.5 8385.9 9726.6 9921.9 9948.4 6916.6 7406.2 7575.4 7674.1 7722.4 7112.8 8375.5 8386.5 8424.9 8459.4 7392.3 7581.4 7596.5 7638.6 7778.5 7431.7 7543.4 7544.1 7654.7 7683.3 7096.4 7125.5 7127.6 7161.1 7165.1 4889.5 4951.0 5067.7 5077.1 5121.4 5087.4 5099.9 5112.8 5181.8 5350.7 02:52:35 INFO - PROCESS | 1668 | 02:52:35 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:5845.34 Median:6706.22 stddev:1889.74 (28.2%) stddev-sans-first:1849.07 02:52:35 INFO - PROCESS | 1668 | Values: 325.1 325.5 331.6 333.8 339.3 6775.2 6792.4 6812.4 6842.2 6860.7 6756.0 6764.2 6788.6 6795.5 6810.7 3884.0 4753.7 4892.5 4914.2 4931.7 4329.4 4559.6 4906.7 4908.2 4921.8 4849.2 4861.4 4865.3 4927.7 4936.9 4904.6 4922.8 4934.1 4946.9 4949.6 4216.4 4223.5 4229.2 4231.0 4235.4 5577.8 6146.4 6471.1 6486.8 6496.9 5756.3 5787.1 5807.7 5820.5 5823.0 7171.7 7190.9 7195.0 7226.3 7227.1 4635.4 4636.8 4641.0 4646.8 4671.8 7501.8 7611.8 7614.9 7625.4 7637.9 4524.3 4666.2 4676.9 4683.1 4695.8 4743.6 4816.1 4825.0 4831.0 4835.0 5767.7 5771.3 5782.1 5793.6 5801.9 6236.3 7206.4 7210.2 7213.9 7218.0 1659.3 1672.6 1673.9 1675.1 1679.8 1013.0 1047.6 1057.3 1062.0 1070.0 5539.5 6770.1 6795.3 6804.4 6808.5 6845.9 6921.9 7296.3 7545.8 7564.6 6656.5 6818.5 6838.0 6847.0 6854.7 3376.7 3881.8 3945.2 3998.0 4004.0 7905.6 7925.9 7926.4 7935.6 7959.1 6093.6 7722.7 7820.0 7841.9 7849.7 7891.6 7926.8 7942.6 7971.4 7975.8 7560.1 7586.9 7588.4 7594.9 7596.7 6078.3 6079.3 6516.1 7124.3 7453.8 5976.0 6377.6 6833.4 7682.7 7919.0 4686.2 4739.0 4741.6 4758.5 4770.4 7420.0 7493.9 7520.9 7529.2 7588.7 6044.5 6456.6 7073.9 7261.2 7270.1 6970.5 7172.0 7251.3 7262.5 7264.0 7000.5 7062.0 7075.9 7078.2 7082.4 7183.7 7214.9 7227.7 7227.8 7254.6 7080.6 7080.8 7097.0 7103.9 7141.9 02:52:35 INFO - PROCESS | 1668 | 02:52:35 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:18445.61 Median:33070.61 stddev:25870.56 (78.2%) stddev-sans-first:25908.77 02:52:35 INFO - PROCESS | 1668 | Values: 687.3 714.3 725.5 743.5 760.4 14735.6 14891.1 14989.3 15015.1 15126.3 13706.7 13735.8 13740.5 13761.6 13764.8 734.5 913.4 923.3 962.1 965.5 935.2 955.1 962.7 969.7 971.2 871.5 939.4 940.8 969.6 1003.8 947.4 983.3 995.5 1003.2 1010.1 698.4 732.5 735.9 753.0 753.3 14546.1 14627.6 14657.3 14667.3 14738.4 12615.1 12618.6 12737.7 12823.9 13018.1 9802.2 9913.3 9920.4 10225.6 10304.9 3495.5 3523.9 3548.2 3641.3 3776.1 77015.0 78094.0 79065.6 79743.2 79761.8 9578.4 9583.5 9672.2 9756.4 9793.0 21399.8 21734.8 21827.1 21835.5 21837.2 7585.5 7633.3 7776.1 7891.4 7984.4 4106.0 4138.4 4182.3 4192.8 4345.6 3549.5 3551.4 3578.7 3620.7 3645.0 651.9 672.5 674.7 677.0 680.7 583.5 585.4 589.8 590.7 601.1 8188.7 8234.8 8294.6 8462.7 8587.7 593.5 602.7 604.8 623.0 641.1 3631.4 3842.5 3903.2 3921.5 3979.7 90071.5 91336.5 91874.5 91925.1 95861.0 77257.0 78310.0 81319.8 82134.4 82347.0 69752.0 70630.0 70681.2 71355.3 73142.5 8469.3 8475.2 8512.0 8514.4 8820.6 48759.0 51356.0 51453.2 51794.5 51942.7 65489.3 66343.0 66751.0 73512.6 74330.6 10695.5 11112.3 11251.1 11301.9 11454.6 10149.5 11856.0 12000.8 12416.2 12553.6 11302.2 11452.8 11455.4 11471.4 11715.3 11494.8 11501.1 11522.7 11727.9 11742.3 10703.7 11230.2 11311.7 11443.2 11604.8 9551.2 9684.0 9749.0 9828.4 9843.5 9623.5 9642.9 9787.1 9813.8 9867.0 02:52:35 INFO - PROCESS | 1668 | -------- Summary: end -------- 02:52:35 INFO - PROCESS | 1668 | 02:52:35 INFO - TEST-INFO | 1668: exit 0 02:52:35 INFO - TEST-OK | dromaeo_css | took 1111040ms 02:52:35 INFO - TEST-START | kraken 02:52:35 INFO - operating with platform_type : mac_ 02:52:35 INFO - Initialising browser for kraken test... 02:52:35 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmpu5v2zl/profile http://localhost:49278/getInfo.html) 02:52:38 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 02:52:38 INFO - PROCESS | 1676 | colorDepth:24 02:52:38 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 02:52:38 INFO - PROCESS | 1676 | __metrics 02:52:38 ERROR - PROCESS | 1676 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 02:52:39 INFO - TEST-INFO | 1676: exit 0 02:52:39 INFO - Browser initialized. 02:52:39 INFO - Running cycle 1/1 for kraken test... 02:52:39 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmpu5v2zl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:52:51 INFO - PROCESS | 1679 | RSS: Main: 221544448 02:52:51 INFO - PROCESS | 1679 | 02:52:54 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 02:52:55 INFO - PROCESS | 1679 | RSS: Main: 233021440 02:52:55 INFO - PROCESS | 1679 | 02:52:58 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 02:52:58 INFO - PROCESS | 1679 | RSS: Main: 242286592 02:52:58 INFO - PROCESS | 1679 | 02:53:02 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 02:53:02 INFO - PROCESS | 1679 | RSS: Main: 245964800 02:53:02 INFO - PROCESS | 1679 | 02:53:05 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 02:53:05 INFO - PROCESS | 1679 | RSS: Main: 247844864 02:53:05 INFO - PROCESS | 1679 | 02:53:07 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:53:08 INFO - PROCESS | 1679 | RSS: Main: 247799808 02:53:08 INFO - PROCESS | 1679 | 02:53:11 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 02:53:11 INFO - PROCESS | 1679 | RSS: Main: 247812096 02:53:11 INFO - PROCESS | 1679 | 02:53:14 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 02:53:15 INFO - PROCESS | 1679 | RSS: Main: 248016896 02:53:15 INFO - PROCESS | 1679 | 02:53:18 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 02:53:18 INFO - PROCESS | 1679 | RSS: Main: 248045568 02:53:18 INFO - PROCESS | 1679 | 02:53:20 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:53:20 INFO - PROCESS | 1679 | RSS: Main: 247484416 02:53:20 INFO - PROCESS | 1679 | 02:53:22 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:53:23 INFO - PROCESS | 1679 | RSS: Main: 247459840 02:53:23 INFO - PROCESS | 1679 | 02:53:25 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:53:26 INFO - PROCESS | 1679 | RSS: Main: 247951360 02:53:26 INFO - PROCESS | 1679 | 02:53:28 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:53:29 INFO - PROCESS | 1679 | RSS: Main: 247812096 02:53:29 INFO - PROCESS | 1679 | 02:53:32 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:53:33 INFO - PROCESS | 1679 | RSS: Main: 247779328 02:53:33 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 02:53:34 INFO - PROCESS | 1679 | RSS: Main: 249835520 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | __start_tp_report 02:53:34 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 02:53:34 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 02:53:34 INFO - PROCESS | 1679 | |i|pagename|runs| 02:53:34 INFO - PROCESS | 1679 | |0;ai-astar;151;168;87;168;105;167;155;165;104;169 02:53:34 INFO - PROCESS | 1679 | |1;audio-beat-detection;150;124;157;168;148;171;123;158;172;157 02:53:34 INFO - PROCESS | 1679 | |2;audio-dft;197;241;274;203;193;195;196;199;229;200 02:53:34 INFO - PROCESS | 1679 | |3;audio-fft;67;66;81;142;60;83;79;89;152;66 02:53:34 INFO - PROCESS | 1679 | |4;audio-oscillator;78;84;128;77;78;85;118;80;79;124 02:53:34 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;102;87;90;86;90;86;89;88;89;86 02:53:34 INFO - PROCESS | 1679 | |6;imaging-darkroom;96;94;95;95;94;94;114;122;93;94 02:53:34 INFO - PROCESS | 1679 | |7;imaging-desaturate;91;93;105;143;145;88;92;91;89;91 02:53:34 INFO - PROCESS | 1679 | |8;json-parse-financial;60;65;66;65;65;64;66;85;65;62 02:53:34 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;64;67;75;67;75;78;71;69;68;69 02:53:34 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;82;83;80;82;82;82;80;80;86;83 02:53:34 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;128;135;129;111;142;146;149;150;150;144 02:53:34 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;165;178;167;165;177;165;163;167;166;165 02:53:34 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;70;68;70;66;69;67;67;67;68;68 02:53:34 INFO - PROCESS | 1679 | __end_tp_report 02:53:34 INFO - PROCESS | 1679 | __start_cc_report 02:53:34 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,1102 02:53:34 INFO - PROCESS | 1679 | __end_cc_report 02:53:34 INFO - PROCESS | 1679 | __startTimestamp1465206814946__endTimestamp 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | ------- Summary: start ------- 02:53:34 INFO - PROCESS | 1679 | Number of tests: 14 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:143.90 Median:167.50 stddev:32.12 (19.2%) stddev-sans-first:33.97 02:53:34 INFO - PROCESS | 1679 | Values: 151.0 168.0 87.0 168.0 105.0 167.0 155.0 165.0 104.0 169.0 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:152.80 Median:157.50 stddev:17.45 (11.1%) stddev-sans-first:18.48 02:53:34 INFO - PROCESS | 1679 | Values: 150.0 124.0 157.0 168.0 148.0 171.0 123.0 158.0 172.0 157.0 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:212.70 Median:201.50 stddev:26.86 (13.3%) stddev-sans-first:27.88 02:53:34 INFO - PROCESS | 1679 | Values: 197.0 241.0 274.0 203.0 193.0 195.0 196.0 199.0 229.0 200.0 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:88.50 Median:73.00 stddev:32.25 (44.2%) stddev-sans-first:33.25 02:53:34 INFO - PROCESS | 1679 | Values: 67.0 66.0 81.0 142.0 60.0 83.0 79.0 89.0 152.0 66.0 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:78.50 stddev:21.15 (26.9%) stddev-sans-first:21.72 02:53:34 INFO - PROCESS | 1679 | Values: 78.0 84.0 128.0 77.0 78.0 85.0 118.0 80.0 79.0 124.0 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:88.50 stddev:4.74 (5.4%) stddev-sans-first:1.69 02:53:34 INFO - PROCESS | 1679 | Values: 102.0 87.0 90.0 86.0 90.0 86.0 89.0 88.0 89.0 86.0 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:99.10 Median:94.00 stddev:10.17 (10.8%) stddev-sans-first:10.73 02:53:34 INFO - PROCESS | 1679 | Values: 96.0 94.0 95.0 95.0 94.0 94.0 114.0 122.0 93.0 94.0 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:102.80 Median:91.00 stddev:22.22 (24.4%) stddev-sans-first:23.15 02:53:34 INFO - PROCESS | 1679 | Values: 91.0 93.0 105.0 143.0 145.0 88.0 92.0 91.0 89.0 91.0 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:66.30 Median:65.00 stddev:6.83 (10.5%) stddev-sans-first:6.86 02:53:34 INFO - PROCESS | 1679 | Values: 60.0 65.0 66.0 65.0 65.0 64.0 66.0 85.0 65.0 62.0 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:70.00 stddev:4.40 (6.3%) stddev-sans-first:4.03 02:53:34 INFO - PROCESS | 1679 | Values: 64.0 67.0 75.0 67.0 75.0 78.0 71.0 69.0 68.0 69.0 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:82.00 stddev:1.83 (2.2%) stddev-sans-first:1.94 02:53:34 INFO - PROCESS | 1679 | Values: 82.0 83.0 80.0 82.0 82.0 82.0 80.0 80.0 86.0 83.0 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:138.40 Median:145.00 stddev:12.66 (8.7%) stddev-sans-first:12.86 02:53:34 INFO - PROCESS | 1679 | Values: 128.0 135.0 129.0 111.0 142.0 146.0 149.0 150.0 150.0 144.0 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:166.50 stddev:5.25 (3.2%) stddev-sans-first:5.46 02:53:34 INFO - PROCESS | 1679 | Values: 165.0 178.0 167.0 165.0 177.0 165.0 163.0 167.0 166.0 165.0 02:53:34 INFO - PROCESS | 1679 | 02:53:34 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.33 (2.0%) stddev-sans-first:1.20 02:53:34 INFO - PROCESS | 1679 | Values: 70.0 68.0 70.0 66.0 69.0 67.0 67.0 67.0 68.0 68.0 02:53:34 INFO - PROCESS | 1679 | -------- Summary: end -------- 02:53:34 INFO - PROCESS | 1679 | 02:53:35 INFO - TEST-INFO | 1679: exit 0 02:53:35 INFO - TEST-OK | kraken | took 59915ms 02:53:35 INFO - SUITE-END | took 1170s 02:53:36 INFO - Completed test suite (00:19:31) 02:53:36 INFO - javascript benchmark 02:53:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3411.505331726901, 3412.948805767914, 3433.9484907726387, 3454.3954807908617, 3460.619331873495, 5662.178984047316, 5693.8576535586635, 5698.088305871067, 5701.885962280752, 5734.827955161347, 5641.407652196522, 5672.943270567293, 5676.914846277309, 5703.5722320826, 5704.971475142629, 5119.0, 5129.358830146231, 5168.715720635382, 5201.219817027439, 5215.478452154774, 5030.723310217936, 5179.989901969117, 5222.138347172712, 5277.8944421111555, 5282.550983166417, 5179.87050323743, 5194.818181363653, 5197.974010129963, 5206.401263854667, 5218.452062533436, 4128.339465685506, 5169.431362550116, 5170.457102004273, 5185.299984502081, 5204.869878253055, 5009.624278179152, 5157.613179011589, 5158.7678554464765, 5184.585233181337, 5210.947890521084, 5624.072028115356, 5643.068893632546, 5702.942970570324, 5712.542996560265, 5733.226014488033, 6002.189704389895, 6162.907556386658, 6164.044573091178, 6181.96909015452, 6213.751449941997, 5320.733963301858, 6197.814065577994, 6279.497640188774, 6303.810885673437, 6319.399657032574, 5792.536597072224, 5797.333306669745, 5816.0, 5836.5622578306375, 5840.620359676693, 6214.627122372672, 6231.937680623227, 6251.0, 6302.149209856702, 6302.306746257908, 6081.695915204222, 6107.144999700045, 6109.2974307955155, 6135.263768347827, 6153.876922461525, 6074.605150665193, 6225.470834979077, 6226.221722284715, 6232.065190221503, 6235.96882015587, 6068.938035465215, 6123.9232566596775, 6136.917362303726, 6194.38056194386, 6199.070139479114, 6171.228596425447, 6175.2589689237575, 6184.072389141665, 6210.937890621036, 6230.968845155836, 4795.184818580849, 4817.205161148441, 4836.056968891033, 4888.144574699413, 4921.311016457699, 3765.246950609889, 4323.481182258086, 4350.369196466495, 4387.27609944362, 4390.05613793036, 5729.312482502127, 5741.196232527366, 5767.798127065533, 5771.192033115367, 5789.652620842763, 5211.687298762086, 5731.0, 5784.334801497882, 6253.280872699606, 6274.278457977391, 5739.62692424991, 5779.930712818142, 5788.160716696139, 5794.942050579524, 5831.562632802556, 4786.593139583105, 5288.127458969227, 5375.892482150413, 5392.110301800238, 5425.050616142159, 6188.9690551546955, 6219.191505104308, 6235.781747638811, 6258.092576576462, 6337.7781777637565, 6239.656818875005, 6254.687265636609, 6257.843553911189, 6262.248530176408, 6287.245530536182, 6300.054991751274, 6310.873782524232, 6331.113644089924, 6333.556651034383, 6340.556161068866, 5996.400359964143, 6134.069235337226, 6137.407629318811, 6156.322804491592, 6204.441600255998, 6217.378262173746, 6237.656928868774, 6260.060990851409, 6264.968675156777, 6285.905711414241, 5968.880622387441, 6047.818565443044, 6130.01919692847, 6149.877002460015, 6175.876482470415, 5353.625246232727, 5952.136940143569, 5993.101034844808, 6000.159977603052, 6044.879102418015, 5182.404023537341, 5867.061270196748, 5885.499732522612, 5906.114082887601, 5913.231279933581, 5099.592032637306, 6071.605345652695, 6073.089036644538, 6076.696165191811, 6094.238220222473, 4954.801807927643, 4993.775280112331, 5937.7921772737745, 5986.07215881539, 6014.398560143951], "value": 5633.925142911245, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.21013982697593, 510.14550627698594, 515.7704821354499, 539.4039586257186, 552.1028328965432, 38392.0, 39996.200075998466, 40011.0, 40109.0, 40158.59841401589, 41319.0, 42019.0, 42062.579374206245, 42063.0, 42230.78884605573, 9375.812483750338, 9532.571034303439, 9563.665271715492, 9634.9518252409, 9643.662471813488, 9516.762080947998, 9758.804823903552, 9781.070798274153, 9785.608575656965, 9803.803923921518, 9753.561089750978, 9760.316777825556, 9802.2158227342, 9817.0, 9824.066713662227, 9710.514474276293, 9777.266704997153, 9780.804383912282, 9797.559109840075, 9817.754556136153, 9691.0, 9777.706668799947, 9818.705438836774, 9822.459764712938, 9864.112229899343, 40138.598614013776, 40881.79559102185, 40923.7953810229, 40951.7952410236, 40969.0, 3173.1432513221303, 3732.9440058399146, 3802.885913422602, 3879.7866117363533, 3894.9610503895165, 28492.572611410844, 29357.85321073402, 29368.706312937025, 29420.705792942226, 29658.70341296581, 2728.8499132547704, 2776.763975062102, 2813.1419916925533, 2831.985840070807, 2845.7154284571584, 472657.0, 475513.0, 475907.0, 478425.0, 480613.0, 64940.0, 65438.01842972359, 66038.33961660418, 66113.6694316535, 66306.66846665736, 70504.64747676332, 72798.63600681962, 73491.26508734844, 74786.6260668704, 74910.87633685498, 13905.582832515041, 17572.38496652611, 17704.734428983575, 17717.7342339865, 17811.643767124584, 13298.0, 13362.131461454974, 13375.665608359868, 13385.665358365924, 13419.194848309133, 17193.312267509158, 20366.694499582518, 20583.176672933216, 20601.0, 20637.864917429684, 7120.893186602205, 8108.5945702714625, 8122.131909660811, 8192.795180120545, 8213.630386632616, 985.3792110970043, 1035.699647102347, 1079.3631757263252, 1112.6161474291357, 1116.6594188772503, 30377.24056898551, 30391.544126838115, 30905.918292859642, 31012.22469438282, 31459.52810707841, 1095.2497539185683, 1126.5212284778936, 1127.301073334538, 1135.6666600716214, 1224.6264889208699, 12752.298623575607, 15932.442364517417, 15965.920170399306, 16021.59946001336, 16285.755713664304, 633423.0, 635788.0, 641504.0, 643916.0, 647758.0, 61093.0, 63530.0, 63682.72634547283, 63724.681376592824, 63799.0, 1493.4474244529595, 1500.1374209829391, 1509.1548732709718, 1511.0857930951615, 1517.355124072287, 30287.697123028487, 30654.386912262074, 31082.222944425666, 31100.844495777375, 31191.532127018567, 54761.45238547563, 56462.0, 56585.71707141438, 56695.716521417125, 56917.43082569121, 602222.0, 602961.0, 603120.0, 604610.0, 604991.0, 28106.73519691578, 28279.71720282853, 28280.43439131247, 28348.724307405802, 28734.281642959093, 45493.31760023668, 45741.0, 48452.03095938132, 48885.0, 49943.250851237986, 38841.6115838838, 40154.9961250971, 40353.0, 40416.68332866802, 40421.797891010356, 39188.02029949274, 39198.216035679696, 39374.80312598419, 39575.60424395835, 39766.403503948044, 38245.80877095597, 38352.42471362876, 38566.80716596399, 38884.2223155541, 39441.802790985865, 32613.0, 33425.83287083549, 33755.493667594514, 33919.0, 34313.31373372469, 21356.7864321361, 25407.110751123622, 25522.744772552778, 25592.616110758725, 25817.870910646077], "value": 20591.08905422819, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.5196244881654, 396.0870194202366, 400.5293779808724, 400.78362170811585, 406.6685651194276, 12797.360131993386, 15576.0, 20132.59734805305, 20734.963251837424, 20856.374308770723, 19948.0, 20219.191232350688, 20345.28791492298, 20696.68954965673, 20711.792882071175, 5120.4367519572725, 5973.22348094747, 6005.099235114746, 6044.24446944132, 6061.272647282311, 4968.2795994580765, 5620.606557540974, 5862.824115276549, 5939.07944268639, 5941.316748573904, 5089.3638295213095, 5787.855303617423, 5796.797112101077, 5911.970440147815, 5919.17131601574, 4782.019685964369, 5868.647881127104, 5996.310424301174, 6051.394860513936, 6083.634981901078, 5436.646617969819, 5763.855903602402, 5811.070228763419, 5956.106584012389, 6011.947909115931, 11607.6517704469, 13504.594862154152, 13611.11527750693, 19901.502462438555, 24243.90902409396, 2176.1621775616345, 2223.2218723446827, 2244.730632324115, 2640.0633854402795, 2682.4903268378944, 9966.302358834884, 9980.850287245697, 10124.98750124989, 10133.543990520444, 10240.436775977318, 1743.1458585293242, 1746.2142036083685, 1746.8689848261304, 1787.347618119377, 1809.782826060868, 76310.0, 111084.0, 116069.83930160674, 117281.0, 119361.40319298435, 11724.58963936258, 11785.3518056506, 11994.280343179436, 12116.15186936906, 12116.636500904988, 15184.01303915242, 15227.543173704807, 15249.085054896741, 15285.006474579117, 15297.847021529866, 8864.335174861782, 8865.980412252673, 8909.821803564022, 8983.191512763882, 8983.505907175168, 7807.0, 8810.295176385875, 8868.113188681209, 8894.86657700135, 8975.967763707127, 4625.421822272216, 4715.834945776881, 4716.9056618867435, 4727.881802954885, 4767.475577686451, 1965.4005528313717, 2040.3776848061484, 2042.0810635213916, 2080.833533317331, 2097.7587577428485, 899.5097671768901, 952.2524818017823, 998.3311181508407, 1015.9034891685278, 1026.2149455666422, 16662.83337166637, 18702.906485467756, 19880.0, 21013.369598912057, 21097.261595844073, 863.5509535041743, 971.5093877591771, 991.5538007896475, 1000.5297510170209, 1004.8593196952432, 10262.897371026344, 10522.328548644216, 11111.83332250017, 11121.27711698737, 11191.216614836883, 517632.0, 659381.0, 665068.0, 676125.0, 679045.0, 90404.54797726101, 106494.0, 117148.24277635843, 124496.0, 136320.0, 975.5902520941222, 999.3604093380243, 1001.5242759689089, 1005.2812239248972, 1035.7928414317016, 17900.91049544796, 18008.729869051713, 18079.45761627153, 18222.72665910039, 18521.0, 51453.48546514637, 51806.74096629493, 51936.62411943212, 54343.91312173655, 54503.90992180214, 76594.61702691451, 77870.6106469464, 79888.60055699684, 81133.59433202796, 81624.18375816403, 7941.6029198541, 8156.306713929145, 8309.961254843145, 8335.208155225244, 8362.45644033136, 9032.548372581476, 11831.822522661994, 12406.0, 12804.423800928795, 12930.0, 10758.5973873683, 10810.35137891729, 10877.945610272214, 11013.614523491638, 11014.889851101387, 8785.385023048326, 10386.688399348031, 10610.0, 10665.200109991627, 11092.667219983414, 10688.57245710194, 10980.396078215455, 11232.494537745657, 11530.1928864982, 11536.307821530734, 7741.380689544935, 8185.38609604294, 8281.54451505149, 8287.461315014745, 8507.489550626982, 9763.85354219673, 11083.279586826831, 11223.71940701489, 11422.600208992646, 11453.25553839031], "value": 9422.0932678559, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1597.6884507521033, 1617.2964760329257, 1853.7126745354467, 1873.7376767252588, 1995.3814317561548, 7949.92050079499, 8663.0, 8837.248833849122, 9060.864087038692, 9077.909220907797, 8781.780455488615, 8782.604782784774, 8872.822543549126, 8903.95548022259, 8936.955315223431, 2478.293686299405, 2495.413577809213, 2501.7998560115147, 2503.486785209037, 2513.1204078572537, 2445.2419749877504, 2487.2911220302126, 2503.4492411669494, 2510.7363726808694, 2514.497100579882, 1994.8005199480008, 2116.8200702940267, 2265.252466685998, 2507.749225077496, 2723.332783468043, 2227.7438094619083, 2285.474340901602, 2479.0331770609478, 2498.150628786212, 2513.158092039169, 2201.7027701260445, 2424.066734307287, 2487.315988103268, 2502.461970676295, 2516.6728325317777, 8980.967188773244, 9018.36871419001, 9088.454692718458, 9092.04533523977, 9102.27181825459, 5515.255440515519, 5652.971735141339, 5706.315242170925, 5722.37053924068, 5773.711314434304, 3023.4104349651825, 3030.2878823476467, 3314.6022477302877, 3519.1729943463265, 3574.2136729919375, 1064.7178497698117, 1089.8910108989116, 1089.9509522071526, 1090.2749671468464, 1094.141099237095, 12120.212186207867, 12137.332446715427, 12309.876901230962, 12311.876881231252, 12341.876581234163, 4927.334809800703, 4929.852104436873, 5031.471695471922, 5050.065737838511, 5096.566791822736, 8889.4666320021, 10455.477226138662, 10470.947645261725, 10482.63310784119, 10495.0, 5536.723163841792, 5555.055640541118, 5565.0, 5591.776328946797, 5607.990561698934, 4457.821687132543, 4834.927476087862, 4917.803287868445, 5002.874928126826, 5037.345145131109, 3987.444896490371, 4010.7192496524963, 4081.77550234734, 4192.706510544293, 4218.325067989107, 1368.7194125204508, 1374.2304309586464, 1374.7662897307482, 1377.373295150704, 1382.8893688504895, 814.5479259011306, 886.3485338276362, 957.9521023948774, 963.3786207895868, 966.5505539923969, 6026.427489388501, 6070.544709146831, 6146.7233974471255, 6214.875702485925, 6225.3774622537385, 910.2399496420563, 1035.7514196592763, 1039.1219419590584, 1044.4307852220559, 1107.9058280046286, 3944.723869329119, 3981.7212795104065, 4042.2319759245647, 4045.474088368493, 4060.3300455424524, 10804.351738895692, 11090.5563777448, 11099.556017759362, 11125.666230013112, 11129.666110016713, 10818.972197641211, 10940.56237750497, 11015.228933974706, 11018.39398833072, 11087.556497740161, 6561.770338038145, 6831.941049137392, 8023.318017968538, 8543.487390756574, 8582.313414926666, 5039.344885165052, 5655.406182350794, 5921.851953701192, 5944.4352786485215, 5949.405059494016, 8057.717979870677, 9413.81172376535, 9663.0, 9728.0, 9765.121139097515, 8146.511209327459, 8385.916140838757, 9726.562304696165, 9921.851172232567, 9948.403095814274, 6916.619585922823, 7406.185319614946, 7575.393968482618, 7674.079110506775, 7722.382209423345, 7112.822179445555, 8375.539345336065, 8386.538740369338, 8424.915750842412, 8459.365547583833, 7392.334689877936, 7581.355584775135, 7596.544207347577, 7638.588160337644, 7778.49439786412, 7431.70273189088, 7543.358814500829, 7544.132424771222, 7654.73208437702, 7683.270089341504, 7096.361327480551, 7125.501214914906, 7127.607981561064, 7161.069061022034, 7165.068541089625, 4889.486603906681, 4951.04839273071, 5067.695938243717, 5077.136886729191, 5121.359830021247, 5087.364079490064, 5099.87250318745, 5112.821051263337, 5181.766820493011, 5350.705711185922], "value": 4685.273604798741, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.1254126399984, 325.54098720803654, 331.5656489998103, 333.76136062715153, 339.33490358896563, 6775.1531058617675, 6792.388685018347, 6812.386885180339, 6842.213145488264, 6860.656967151647, 6755.9662201688925, 6764.222114456839, 6788.626625535593, 6795.524313298071, 6810.662775240122, 3884.0, 4753.714777113367, 4892.486288939664, 4914.238293064581, 4931.7040977541465, 4329.415528903605, 4559.612432943204, 4906.656534042619, 4908.190148625473, 4921.8277360292395, 4849.151398505265, 4861.416630004387, 4865.294532292815, 4927.728974906378, 4936.851894443154, 4904.558589726906, 4922.753862306906, 4934.1365261079345, 4946.925796113062, 4949.579285760697, 4216.430781844442, 4223.472065991751, 4229.217594744951, 4231.0, 4235.428217190702, 5577.832665020056, 6146.35463276354, 6471.05868239531, 6486.805395838085, 6496.935030649727, 5756.338021127582, 5787.13193021046, 5807.709614519299, 5820.476157145878, 5823.039198532237, 7171.748988785367, 7190.892136617955, 7195.0, 7226.313500217471, 7227.084822522036, 4635.420572428447, 4636.79134438951, 4641.0, 4646.79089440976, 4671.813127474897, 7501.812454688622, 7611.771646850603, 7614.885776713354, 7625.389968802538, 7637.923620763721, 4524.321351797191, 4666.230072038144, 4676.883077923079, 4683.133620280191, 4695.765211739399, 4743.5967942724565, 4816.133096042746, 4824.962633033914, 4830.951690483121, 4834.975825120852, 5767.653940763567, 5771.336296325892, 5782.132680097935, 5793.565482588822, 5801.9129713054335, 6236.345183755731, 7206.351428371472, 7210.206877243604, 7213.89179162313, 7217.953396757546, 1659.311385774914, 1672.5567724553002, 1673.8785014040623, 1675.1289329548567, 1679.7984241891052, 1012.9848052279222, 1047.5700668587324, 1057.3338796558119, 1061.9734506637242, 1069.978600427987, 5539.473749993744, 6770.052192693027, 6795.286494918061, 6804.387605115563, 6808.489363297674, 6845.931540684529, 6921.8961715574305, 7296.306850849161, 7545.849083018309, 7564.583947882919, 6656.467482601381, 6818.522703410714, 6838.0, 6846.9729540569315, 6854.725810967506, 3376.716847597946, 3881.8059097045034, 3945.2306800174265, 3997.960020399817, 4003.9990002499376, 7905.56519391439, 7925.8811117833275, 7926.445148839526, 7935.603219838985, 7959.08470525897, 6093.634381937098, 7722.7217970698775, 7820.0, 7841.882371764428, 7849.686012559548, 7891.605419729105, 7926.801829954182, 7942.563159026309, 7971.44199906001, 7975.8404831903035, 7560.092788865371, 7586.924130758622, 7588.392928565812, 7594.924050759421, 7596.658150383357, 6078.331383547895, 6079.270487541523, 6516.087747715418, 7124.323189297009, 7453.776386708408, 5976.043832986701, 6377.585456945284, 6833.41915937131, 7682.692692292246, 7919.049714034353, 4686.226772582486, 4738.952610473852, 4741.620670346295, 4758.5241475852135, 4770.356001939694, 7419.962900185465, 7493.850122997618, 7520.8871866920945, 7529.171791103084, 7588.734394296173, 6044.486218671462, 6456.612603243821, 7073.893891591527, 7261.237570055068, 7270.091238595176, 6970.546914450544, 7172.0, 7251.274872512707, 7262.49162558637, 7263.963680181565, 7000.47496437759, 7062.046623705939, 7075.858482830417, 7078.1506219254015, 7082.433405327664, 7183.748568800067, 7214.855702885808, 7227.710891564278, 7227.819304517218, 7254.600996945219, 7080.645967701491, 7080.752173673896, 7096.964515177391, 7103.893441598483, 7141.857162856818], "value": 5212.888604942176, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.2955220898582, 714.2500374606664, 725.5392825555774, 743.5352904434728, 760.3574979142625, 14735.631609209775, 14891.106533607965, 14989.325480353384, 15015.099094054365, 15126.319315630795, 13706.725865482684, 13735.793963090562, 13740.519081832137, 13761.587152385444, 13764.793528097087, 734.4895297768053, 913.4062859141545, 923.2660035271941, 962.0716009051264, 965.4882912056621, 935.2424536125726, 955.0831202046044, 962.687126683827, 969.6896992962255, 971.2375785008769, 871.4727589808169, 939.3940908114262, 940.7554035950668, 969.5976169889487, 1003.8142943555465, 947.4362754161332, 983.2969426859794, 995.5271246158089, 1003.1924300937742, 1010.1111022300365, 698.3505340033767, 732.4543215304632, 735.871222536054, 752.9698812047511, 753.3069575990103, 14546.127232366092, 14627.63430914225, 14657.267136643126, 14667.266636668124, 14738.410463581444, 12615.053870959715, 12618.55835045769, 12737.681557961032, 12823.935880320632, 13018.088733788638, 9802.166815820592, 9913.256505762096, 9920.40477571348, 10225.590976360936, 10304.896951030469, 3495.4931534927546, 3523.859045638171, 3548.201654627714, 3641.308151451216, 3776.131489757371, 77015.0, 78094.0, 79065.60467197685, 79743.20256797416, 79761.80357294645, 9578.377405468622, 9583.474770871095, 9672.22622190223, 9756.414615123116, 9793.020697930293, 21399.786002140092, 21734.78265217359, 21827.126914923225, 21835.454113646967, 21837.235696750857, 7585.544867307979, 7633.274838890296, 7776.144624091346, 7891.447598668038, 7984.361251099898, 4106.035081755785, 4138.441310423054, 4182.309918863414, 4192.81132349045, 4345.608895199384, 3549.4675798629896, 3551.3785087609567, 3578.6600272974024, 3620.728445366608, 3645.0, 651.8663673946925, 672.4989882537443, 674.6694119881223, 677.0183234310269, 680.7106979533777, 583.4661284924332, 585.3561082808961, 589.840962508664, 590.6721769418015, 601.128363872378, 8188.672453101928, 8234.752957411287, 8294.626741796634, 8462.746117616482, 8587.65649374018, 593.5370411079365, 602.6625089949642, 604.7762327938691, 622.9626222426668, 641.0608458608161, 3631.3504927548174, 3842.4773474061426, 3903.160820423566, 3921.490206273223, 3979.7413168143976, 90071.54964225137, 91336.543317283, 91874.54062729643, 91925.08074919165, 95861.04138958521, 77257.0, 78310.0, 81319.78020329581, 82134.3573128534, 82347.0, 69752.0, 70630.0, 70681.23296917618, 71355.2864471359, 73142.53714925672, 8469.280111190617, 8475.237228649452, 8512.0, 8514.446560973516, 8820.5589720515, 48759.0, 51356.0, 51453.22820157626, 51794.48205518047, 51942.74028629833, 65489.345106548324, 66343.0, 66751.0, 73512.63243683748, 74330.62834686009, 10695.465226738788, 11112.333260004103, 11251.099912006855, 11301.943490282825, 11454.599089031844, 10149.49252537385, 11855.992240659296, 12000.759984800081, 12416.192947458387, 12553.556341020534, 11302.196596373014, 11452.797456267304, 11455.369954652575, 11471.369074700971, 11715.297082175097, 11494.770104598028, 11501.137414693763, 11522.711932201426, 11727.882721172678, 11742.295462272292, 10703.678889633324, 11230.21388502797, 11311.660650180507, 11443.198976071595, 11604.825927611262, 9551.188149007412, 9684.0, 9748.951255243963, 9828.361156524803, 9843.507824608883, 9623.518824058629, 9642.903570964201, 9787.070228328297, 9813.803723925625, 9866.950665246628], "value": 7004.712989822922, "unit": "score"}], "name": "dromaeo_css", "value": 7562.171756200553, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 168.0, 87.0, 168.0, 105.0, 167.0, 155.0, 165.0, 104.0, 169.0], "value": 143.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 124.0, 157.0, 168.0, 148.0, 171.0, 123.0, 158.0, 172.0, 157.0], "value": 152.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 241.0, 274.0, 203.0, 193.0, 195.0, 196.0, 199.0, 229.0, 200.0], "value": 212.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 81.0, 142.0, 60.0, 83.0, 79.0, 89.0, 152.0, 66.0], "value": 88.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 84.0, 128.0, 77.0, 78.0, 85.0, 118.0, 80.0, 79.0, 124.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 87.0, 90.0, 86.0, 90.0, 86.0, 89.0, 88.0, 89.0, 86.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 95.0, 94.0, 94.0, 114.0, 122.0, 93.0, 94.0], "value": 99.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 105.0, 143.0, 145.0, 88.0, 92.0, 91.0, 89.0, 91.0], "value": 102.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 65.0, 66.0, 65.0, 65.0, 64.0, 66.0, 85.0, 65.0, 62.0], "value": 66.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 75.0, 67.0, 75.0, 78.0, 71.0, 69.0, 68.0, 69.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 80.0, 82.0, 82.0, 82.0, 80.0, 80.0, 86.0, 83.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 135.0, 129.0, 111.0, 142.0, 146.0, 149.0, 150.0, 150.0, 144.0], "value": 138.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 178.0, 167.0, 165.0, 177.0, 165.0, 163.0, 167.0, 166.0, 165.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 70.0, 66.0, 69.0, 67.0, 67.0, 67.0, 68.0, 68.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1575.0, "alertThreshold": 2.0}]} 02:53:36 INFO - Return code: 0 02:53:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:53:36 ERROR - # TBPL SUCCESS # 02:53:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:53:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:53:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:53:36 INFO - 2016-06-06 02:53:36.251 screenresolution[1684:11041] starting screenresolution argv=screenresolution get 02:53:36 INFO - 2016-06-06 02:53:36.254 screenresolution[1684:11041] Display 0: 1600x1200x32@60 02:53:36 INFO - 2016-06-06 02:53:36.259 screenresolution[1685:11044] starting screenresolution argv=screenresolution list 02:53:36 INFO - Available Modes on Display 0 02:53:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:53:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:53:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:53:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:53:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:53:36 INFO - Intel Iris: 02:53:36 INFO - Chipset Model: Intel Iris 02:53:36 INFO - Type: GPU 02:53:36 INFO - Bus: Built-In 02:53:36 INFO - VRAM (Dynamic, Max): 1536 MB 02:53:36 INFO - Vendor: Intel (0x8086) 02:53:36 INFO - Device ID: 0x0a2e 02:53:36 INFO - Revision ID: 0x0009 02:53:36 INFO - Displays: 02:53:36 INFO - Display: 02:53:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:53:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:53:36 INFO - Display Serial Number: 1600x1200 60 02:53:36 INFO - Main Display: Yes 02:53:36 INFO - Mirror: Off 02:53:36 INFO - Online: Yes 02:53:36 INFO - Rotation: Supported 02:53:36 INFO - Return code: 0 02:53:36 INFO - Running post-action listener: _resource_record_post_action 02:53:36 INFO - Running post-run listener: _resource_record_post_run 02:53:37 WARNING - Exception when reporting resource usage: Traceback (most recent call last): 02:53:37 WARNING - File "/builds/slave/test/scripts/mozharness/base/python.py", line 507, in _resource_record_post_run 02:53:37 WARNING - self._resource_monitor.stop() 02:53:37 WARNING - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/mozsystemmonitor/resourcemonitor.py", line 255, in stop 02:53:37 WARNING - assert done 02:53:37 WARNING - AssertionError 02:53:37 INFO - Running post-run listener: _upload_blobber_files 02:53:37 INFO - Blob upload gear active. 02:53:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:53:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:53:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:53:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:53:37 INFO - (blobuploader) - INFO - Open directory for files ... 02:53:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:53:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:53:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:53:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:53:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:53:38 INFO - (blobuploader) - INFO - Done attempting. 02:53:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:53:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:53:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:53:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:53:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:53:39 INFO - (blobuploader) - INFO - Done attempting. 02:53:39 INFO - (blobuploader) - INFO - Iteration through files over. 02:53:39 INFO - Return code: 0 02:53:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:53:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:53:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8474d1efc2c6ff5243345c6aadf24637bfe609ac46469178edd63f136feecea3a9979fda3e2dc0a2c0438ca7477a95e2396aa2819487c2642ef8d6a6422e24f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81850305708d68d4fef2179dd414c621b5548bd2bf2ca25f8db117f3de487a13c23cba1f172d90611556feab097a7b0ba97e141f37a58bfb9f583ff0514d679d"} 02:53:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:53:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:53:39 INFO - Contents: 02:53:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8474d1efc2c6ff5243345c6aadf24637bfe609ac46469178edd63f136feecea3a9979fda3e2dc0a2c0438ca7477a95e2396aa2819487c2642ef8d6a6422e24f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81850305708d68d4fef2179dd414c621b5548bd2bf2ca25f8db117f3de487a13c23cba1f172d90611556feab097a7b0ba97e141f37a58bfb9f583ff0514d679d"} 02:53:39 INFO - Running post-run listener: copy_logs_to_upload_dir 02:53:39 INFO - Copying logs to upload dir... 02:53:39 INFO - mkdir: /builds/slave/test/build/upload/logs 02:53:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1326.685690 ========= master_lag: 4.51 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 11 secs) (at 2016-06-06 02:53:44.194484) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 02:53:44.201990) ========= 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.Z7P5OnW4WJ/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.50DGKGbyve/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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/e8474d1efc2c6ff5243345c6aadf24637bfe609ac46469178edd63f136feecea3a9979fda3e2dc0a2c0438ca7477a95e2396aa2819487c2642ef8d6a6422e24f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81850305708d68d4fef2179dd414c621b5548bd2bf2ca25f8db117f3de487a13c23cba1f172d90611556feab097a7b0ba97e141f37a58bfb9f583ff0514d679d"} build_url:https://queue.taskcluster.net/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011174 build_url: 'https://queue.taskcluster.net/v1/task/SW5EPoyXTrmmOwNpTEFHGQ/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/e8474d1efc2c6ff5243345c6aadf24637bfe609ac46469178edd63f136feecea3a9979fda3e2dc0a2c0438ca7477a95e2396aa2819487c2642ef8d6a6422e24f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81850305708d68d4fef2179dd414c621b5548bd2bf2ca25f8db117f3de487a13c23cba1f172d90611556feab097a7b0ba97e141f37a58bfb9f583ff0514d679d"}' ========= master_lag: 0.93 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 02:53:45.146947) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:53:45.147280) ========= 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.Z7P5OnW4WJ/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.50DGKGbyve/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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.005121 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:53:45.172651) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 02:53:45.172958) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 02:53:45.174178) ========= ========= Total master_lag: 5.53 =========