builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0386 starttime: 1465220874.5 results: success (0) buildid: 20160606010529 builduid: 78750d9664274d05bee96204469a3a74 revision: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:54.499384) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:54.499998) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:54.500458) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:54.545287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:54.545622) ========= 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.NR8iTrYYtn/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.GKpv4XcW1Q/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 06:47:54-- 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% 74.7M=0s 2016-06-06 06:47:54 (74.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106204 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:54.672106) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:54.672430) ========= 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.NR8iTrYYtn/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.GKpv4XcW1Q/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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.020432 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:54.721143) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 06:47:54.721502) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d8adb38dd576398dacbfb96d2c247f8e0158bdf1 --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 d8adb38dd576398dacbfb96d2c247f8e0158bdf1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NR8iTrYYtn/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.GKpv4XcW1Q/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 06:47:54,802 truncating revision to first 12 chars 2016-06-06 06:47:54,802 Setting DEBUG logging. 2016-06-06 06:47:54,802 attempt 1/10 2016-06-06 06:47:54,802 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d8adb38dd576?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 06:47:55,994 unpacking tar archive at: mozilla-aurora-d8adb38dd576/testing/mozharness/ program finished with exit code 0 elapsedTime=1.459366 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 06:47:56.201551) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:56.201869) ========= script_repo_revision: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:56.202240) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:56.202519) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:56.219554) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-06-06 06:47:56.219934) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NR8iTrYYtn/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.GKpv4XcW1Q/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 06:47:56 INFO - MultiFileLogger online at 20160606 06:47:56 in /builds/slave/test 06:47:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 06:47:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:47:56 INFO - {'append_to_log': False, 06:47:56 INFO - 'base_work_dir': '/builds/slave/test', 06:47:56 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:47:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:47:56 INFO - 'branch': 'Mozilla-Aurora', 06:47:56 INFO - 'buildbot_json_path': 'buildprops.json', 06:47:56 INFO - 'config_files': ('talos/mac_config.py',), 06:47:56 INFO - 'default_actions': ('clobber', 06:47:56 INFO - 'read-buildbot-config', 06:47:56 INFO - 'download-and-extract', 06:47:56 INFO - 'populate-webroot', 06:47:56 INFO - 'create-virtualenv', 06:47:56 INFO - 'install', 06:47:56 INFO - 'run-tests'), 06:47:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:47:56 INFO - 'download_minidump_stackwalk': True, 06:47:56 INFO - 'download_symbols': 'ondemand', 06:47:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:47:56 INFO - 'tooltool.py': '/tools/tooltool.py', 06:47:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:47:56 INFO - '/tools/misc-python/virtualenv.py')}, 06:47:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:47:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:47:56 INFO - 'installer_path': 'installer.exe', 06:47:56 INFO - 'log_level': 'info', 06:47:56 INFO - 'log_name': 'talos', 06:47:56 INFO - 'log_to_console': True, 06:47:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:47:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:47:56 INFO - 'opt_config_files': (), 06:47:56 INFO - 'pip_index': False, 06:47:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:47:56 INFO - 'cmd': ('bash', 06:47:56 INFO - '-c', 06:47:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:47:56 INFO - 'enabled': True, 06:47:56 INFO - 'halt_on_failure': False, 06:47:56 INFO - 'name': 'check_screen_resolution'},), 06:47:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:47:56 INFO - 'cmd': ('bash', 06:47:56 INFO - '-c', 06:47:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:47:56 INFO - 'enabled': True, 06:47:56 INFO - 'halt_on_failure': False, 06:47:56 INFO - 'name': 'check_screen_resolution'},), 06:47:56 INFO - 'run_cmd_checks_enabled': True, 06:47:56 INFO - 'sps_profile': False, 06:47:56 INFO - 'sps_profile_interval': 0, 06:47:56 INFO - 'suite': 'dromaeojs', 06:47:56 INFO - 'system_bits': '32', 06:47:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:47:56 INFO - 'title': 't-yosemite-r7-0386', 06:47:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:47:56 INFO - 'use_talos_json': True, 06:47:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:47:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:47:56 INFO - 'work_dir': 'build'} 06:47:56 INFO - ##### 06:47:56 INFO - ##### Running clobber step. 06:47:56 INFO - ##### 06:47:56 INFO - Running pre-action listener: _resource_record_pre_action 06:47:56 INFO - Running main action method: clobber 06:47:56 INFO - rmtree: /builds/slave/test/build 06:47:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:47:57 INFO - Running post-action listener: _resource_record_post_action 06:47:57 INFO - ##### 06:47:57 INFO - ##### Running read-buildbot-config step. 06:47:57 INFO - ##### 06:47:57 INFO - Running pre-action listener: _resource_record_pre_action 06:47:57 INFO - Running main action method: read_buildbot_config 06:47:57 INFO - Using buildbot properties: 06:47:57 INFO - { 06:47:57 INFO - "project": "", 06:47:57 INFO - "product": "firefox", 06:47:57 INFO - "script_repo_revision": "production", 06:47:57 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 06:47:57 INFO - "repository": "", 06:47:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 06:47:57 INFO - "buildid": "20160606010529", 06:47:57 INFO - "pgo_build": "False", 06:47:57 INFO - "basedir": "/builds/slave/test", 06:47:57 INFO - "buildnumber": 17, 06:47:57 INFO - "slavename": "t-yosemite-r7-0386", 06:47:57 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 06:47:57 INFO - "platform": "yosemite_r7", 06:47:57 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 06:47:57 INFO - "branch": "mozilla-aurora", 06:47:57 INFO - "repo_path": "releases/mozilla-aurora", 06:47:57 INFO - "revision": "d8adb38dd576398dacbfb96d2c247f8e0158bdf1", 06:47:57 INFO - "stage_platform": "macosx64", 06:47:57 INFO - "builduid": "78750d9664274d05bee96204469a3a74", 06:47:57 INFO - "slavebuilddir": "test" 06:47:57 INFO - } 06:47:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 06:47:57 INFO - Running post-action listener: _resource_record_post_action 06:47:57 INFO - ##### 06:47:57 INFO - ##### Running download-and-extract step. 06:47:57 INFO - ##### 06:47:57 INFO - Running pre-action listener: _resource_record_pre_action 06:47:57 INFO - Running main action method: download_and_extract 06:47:57 INFO - mkdir: /builds/slave/test/build/tests 06:47:57 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')]} 06:47:57 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:47:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 06:47:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 06:47:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:47:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:47:58 INFO - Downloaded 1199 bytes. 06:47:58 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 06:47:58 INFO - Using the following test package requirements: 06:47:58 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 06:47:58 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:58 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 06:47:58 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:58 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 06:47:58 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:58 INFO - u'jsshell-mac.zip'], 06:47:58 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:58 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 06:47:58 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 06:47:58 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:58 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 06:47:58 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:58 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 06:47:58 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:58 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 06:47:58 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:58 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 06:47:58 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 06:47:58 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')]} 06:47:58 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:47:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:47:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:47:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:47:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:48:00 INFO - Downloaded 18466859 bytes. 06:48:00 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'] 06:48:00 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 06:48:03 INFO - Return code: 0 06:48:03 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 06:48: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')]} 06:48:03 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:48:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:48:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:48:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:48:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:48:04 INFO - Downloaded 18466859 bytes. 06:48:04 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'] 06:48:04 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 06:48:07 INFO - Return code: 0 06:48:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:07 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:48:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 06:48:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 06:48:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:48:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:48:08 INFO - Downloaded 10840145 bytes. 06:48:08 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'] 06:48:08 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 06:48:09 INFO - Return code: 0 06:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:09 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 06:48:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:48:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:48:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 06:48:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:48:12 INFO - Downloaded 114179823 bytes. 06:48:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:48:12 INFO - mkdir: /builds/slave/test/properties 06:48:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:48:12 INFO - Writing to file /builds/slave/test/properties/build_url 06:48:12 INFO - Contents: 06:48:12 INFO - build_url:https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:48:13 INFO - Running post-action listener: _resource_record_post_action 06:48:13 INFO - Running post-action listener: set_extra_try_arguments 06:48:13 INFO - ##### 06:48:13 INFO - ##### Running populate-webroot step. 06:48:13 INFO - ##### 06:48:13 INFO - Running pre-action listener: _resource_record_pre_action 06:48:13 INFO - Running main action method: populate_webroot 06:48:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:48:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:48:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:48:13 INFO - '--tppagecycles', 06:48:13 INFO - '7'], 06:48:13 INFO - 'tests': ['tsvgm']}}, 06:48:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:48:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:48:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:48:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:48:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:48:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:48:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:13 INFO - 'talos_options': ['--disable-e10s'], 06:48:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:48:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:48:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:13 INFO - 'talos_options': ['--disable-e10s'], 06:48:13 INFO - 'tests': ['damp', 'tps']}, 06:48:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:13 INFO - 'tests': ['damp', 'tps']}, 06:48:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:48:13 INFO - 'tests': ['dromaeo_dom']}, 06:48:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:48:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:48:13 INFO - 'tests': ['a11yr', 06:48:13 INFO - 'ts_paint', 06:48:13 INFO - 'tpaint', 06:48:13 INFO - 'sessionrestore', 06:48:13 INFO - 'sessionrestore_no_auto_restore', 06:48:13 INFO - 'tabpaint']}, 06:48:13 INFO - 'other-e10s': {'tests': ['a11yr', 06:48:13 INFO - 'ts_paint', 06:48:13 INFO - 'tpaint', 06:48:13 INFO - 'sessionrestore', 06:48:13 INFO - 'sessionrestore_no_auto_restore', 06:48:13 INFO - 'tabpaint']}, 06:48:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:48:13 INFO - 'ts_paint', 06:48:13 INFO - 'tpaint', 06:48:13 INFO - 'sessionrestore', 06:48:13 INFO - 'sessionrestore_no_auto_restore', 06:48:13 INFO - 'tabpaint']}, 06:48:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:48:13 INFO - 'ts_paint', 06:48:13 INFO - 'tpaint', 06:48:13 INFO - 'sessionrestore', 06:48:13 INFO - 'sessionrestore_no_auto_restore', 06:48:13 INFO - 'tabpaint']}, 06:48:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:48:13 INFO - 'tests': ['a11yr', 06:48:13 INFO - 'ts_paint', 06:48:13 INFO - 'tpaint', 06:48:13 INFO - 'sessionrestore', 06:48:13 INFO - 'sessionrestore_no_auto_restore', 06:48:13 INFO - 'tabpaint']}, 06:48:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:48:13 INFO - 'tests': ['a11yr', 06:48:13 INFO - 'ts_paint', 06:48:13 INFO - 'tpaint', 06:48:13 INFO - 'sessionrestore', 06:48:13 INFO - 'sessionrestore_no_auto_restore', 06:48:13 INFO - 'tabpaint']}, 06:48:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:48:13 INFO - 'tests': ['tsvgx', 06:48:13 INFO - 'tsvgr_opacity', 06:48:13 INFO - 'tart', 06:48:13 INFO - 'tscrollx', 06:48:13 INFO - 'cart']}, 06:48:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:48:13 INFO - 'tsvgr_opacity', 06:48:13 INFO - 'tart', 06:48:13 INFO - 'tscrollx', 06:48:13 INFO - 'cart']}, 06:48:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:13 INFO - 'talos_options': ['--disable-e10s'], 06:48:13 INFO - 'tests': ['tp5o']}, 06:48:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:13 INFO - 'tests': ['tp5o']}, 06:48:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:13 INFO - 'talos_options': ['--disable-e10s', 06:48:13 INFO - '--xperf_path', 06:48:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:48:13 INFO - 'tests': ['tp5n']}, 06:48:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:13 INFO - 'talos_options': ['--xperf_path', 06:48:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:48:13 INFO - 'tests': ['tp5n']}}, 06:48:13 INFO - 'talos.zip': {'path': '', 06:48:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:48:13 INFO - Running post-action listener: _resource_record_post_action 06:48:13 INFO - ##### 06:48:13 INFO - ##### Running create-virtualenv step. 06:48:13 INFO - ##### 06:48:13 INFO - Running pre-action listener: _resource_record_pre_action 06:48:13 INFO - Running main action method: create_virtualenv 06:48:13 INFO - Creating virtualenv /builds/slave/test/build/venv 06:48:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:48:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:48:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:48:13 INFO - Using real prefix '/tools/python27' 06:48:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:48:14 INFO - Installing distribute.............................................................................................................................................................................................done. 06:48:16 INFO - Installing pip.................done. 06:48:16 INFO - Return code: 0 06:48:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:48:16 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')]} 06:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:16 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')]} 06:48:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:16 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 0x10a1a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7ebdd4ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044e00>, '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 0x109eaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a03fb20>, '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.NR8iTrYYtn/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.GKpv4XcW1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 06:48:16 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 06:48:16 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 06:48:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NR8iTrYYtn/Render', 06:48:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:16 INFO - 'HOME': '/Users/cltbld', 06:48:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:16 INFO - 'LOGNAME': 'cltbld', 06:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:16 INFO - 'MOZ_NO_REMOTE': '1', 06:48:16 INFO - 'NO_EM_RESTART': '1', 06:48:16 INFO - 'PAGER': '/bin/cat', 06:48:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:16 INFO - 'PWD': '/builds/slave/test', 06:48:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:16 INFO - 'SHELL': '/bin/bash', 06:48:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GKpv4XcW1Q/Listeners', 06:48:16 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 06:48:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:16 INFO - 'USER': 'cltbld', 06:48:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:16 INFO - 'XPC_FLAGS': '0x0', 06:48:16 INFO - 'XPC_SERVICE_NAME': '0', 06:48:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:48:17 INFO - Cleaning up... 06:48:17 INFO - Return code: 0 06:48:17 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 06:48: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')]} 06:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48: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')]} 06:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7ebdd4ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044e00>, '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 0x109eaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a03fb20>, '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.NR8iTrYYtn/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.GKpv4XcW1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 06:48:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 06:48:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 06:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NR8iTrYYtn/Render', 06:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:17 INFO - 'HOME': '/Users/cltbld', 06:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:17 INFO - 'LOGNAME': 'cltbld', 06:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:17 INFO - 'MOZ_NO_REMOTE': '1', 06:48:17 INFO - 'NO_EM_RESTART': '1', 06:48:17 INFO - 'PAGER': '/bin/cat', 06:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:17 INFO - 'PWD': '/builds/slave/test', 06:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:17 INFO - 'SHELL': '/bin/bash', 06:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GKpv4XcW1Q/Listeners', 06:48:17 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 06:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:17 INFO - 'USER': 'cltbld', 06:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:17 INFO - 'XPC_FLAGS': '0x0', 06:48:17 INFO - 'XPC_SERVICE_NAME': '0', 06:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:17 INFO - Downloading/unpacking psutil>=0.7.1 06:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:48:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:48:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:48:20 INFO - Installing collected packages: psutil 06:48:20 INFO - Running setup.py install for psutil 06:48:20 INFO - building 'psutil._psutil_osx' extension 06:48:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 06:48:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 06:48:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 06:48:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 06:48:21 INFO - building 'psutil._psutil_posix' extension 06:48:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 06:48:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:48:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:48:21 INFO - ^ 06:48:21 INFO - 1 warning generated. 06:48:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 06:48:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:48:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:48:21 INFO - Successfully installed psutil 06:48:21 INFO - Cleaning up... 06:48:21 INFO - Return code: 0 06:48:21 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 06:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7ebdd4ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044e00>, '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 0x109eaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a03fb20>, '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.NR8iTrYYtn/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.GKpv4XcW1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 06:48:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 06:48:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 06:48:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NR8iTrYYtn/Render', 06:48:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:21 INFO - 'HOME': '/Users/cltbld', 06:48:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:21 INFO - 'LOGNAME': 'cltbld', 06:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:21 INFO - 'MOZ_NO_REMOTE': '1', 06:48:21 INFO - 'NO_EM_RESTART': '1', 06:48:21 INFO - 'PAGER': '/bin/cat', 06:48:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:21 INFO - 'PWD': '/builds/slave/test', 06:48:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:21 INFO - 'SHELL': '/bin/bash', 06:48:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GKpv4XcW1Q/Listeners', 06:48:21 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 06:48:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:21 INFO - 'USER': 'cltbld', 06:48:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:21 INFO - 'XPC_FLAGS': '0x0', 06:48:21 INFO - 'XPC_SERVICE_NAME': '0', 06:48:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:21 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:48:21 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 06:48:21 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 06:48:21 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 06:48:21 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 06:48:21 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 06:48:21 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 06:48:24 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:48:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:48:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:48:24 INFO - Installing collected packages: mozsystemmonitor 06:48:24 INFO - Running setup.py install for mozsystemmonitor 06:48:24 INFO - Successfully installed mozsystemmonitor 06:48:24 INFO - Cleaning up... 06:48:24 INFO - Return code: 0 06:48:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7ebdd4ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044e00>, '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 0x109eaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a03fb20>, '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.NR8iTrYYtn/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.GKpv4XcW1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 06:48:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:48:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NR8iTrYYtn/Render', 06:48:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:24 INFO - 'HOME': '/Users/cltbld', 06:48:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:24 INFO - 'LOGNAME': 'cltbld', 06:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:24 INFO - 'MOZ_NO_REMOTE': '1', 06:48:24 INFO - 'NO_EM_RESTART': '1', 06:48:24 INFO - 'PAGER': '/bin/cat', 06:48:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:24 INFO - 'PWD': '/builds/slave/test', 06:48:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:24 INFO - 'SHELL': '/bin/bash', 06:48:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GKpv4XcW1Q/Listeners', 06:48:24 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 06:48:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:24 INFO - 'USER': 'cltbld', 06:48:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:24 INFO - 'XPC_FLAGS': '0x0', 06:48:24 INFO - 'XPC_SERVICE_NAME': '0', 06:48:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:24 INFO - Downloading/unpacking blobuploader==1.2.4 06:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:27 INFO - Downloading blobuploader-1.2.4.tar.gz 06:48:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:48:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:48: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 06:48: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 06:48: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 06:48: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 06:48: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 06:48: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 06:48:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:48:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:48: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 06:48: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 06:48: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 06:48: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 06:48: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 06:48: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 06:48:28 INFO - Downloading docopt-0.6.1.tar.gz 06:48:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:48:28 INFO - Installing collected packages: blobuploader, requests, docopt 06:48:28 INFO - Running setup.py install for blobuploader 06:48:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:48:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:48:28 INFO - Running setup.py install for requests 06:48:29 INFO - Running setup.py install for docopt 06:48:29 INFO - Successfully installed blobuploader requests docopt 06:48:29 INFO - Cleaning up... 06:48:29 INFO - Return code: 0 06:48:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7ebdd4ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044e00>, '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 0x109eaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a03fb20>, '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.NR8iTrYYtn/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.GKpv4XcW1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 06:48:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:48:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:48:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NR8iTrYYtn/Render', 06:48:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:29 INFO - 'HOME': '/Users/cltbld', 06:48:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:29 INFO - 'LOGNAME': 'cltbld', 06:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:29 INFO - 'MOZ_NO_REMOTE': '1', 06:48:29 INFO - 'NO_EM_RESTART': '1', 06:48:29 INFO - 'PAGER': '/bin/cat', 06:48:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:29 INFO - 'PWD': '/builds/slave/test', 06:48:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:29 INFO - 'SHELL': '/bin/bash', 06:48:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GKpv4XcW1Q/Listeners', 06:48:29 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 06:48:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:29 INFO - 'USER': 'cltbld', 06:48:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:29 INFO - 'XPC_FLAGS': '0x0', 06:48:29 INFO - 'XPC_SERVICE_NAME': '0', 06:48:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:48:29 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Y3Sq_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:48:29 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-JI5KY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:48:29 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-FNzRSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:48:29 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-KoGPDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:48:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-KCAq_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:48:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Hdsfe5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:48:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-ehcl6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:48:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-ZKVHCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:48:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-fZ4SbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:48:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Xj9JgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:48:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-3n4cJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:48:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-abCVK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:48:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-no_E4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:48:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-o2f9tS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:31 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-VZ0HhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:48:31 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-S6XcOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:48:31 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-atzW0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:48:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:48:31 INFO - Running setup.py install for manifestparser 06:48:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:48:31 INFO - Running setup.py install for mozcrash 06:48:31 INFO - Running setup.py install for mozdebug 06:48:31 INFO - Running setup.py install for mozdevice 06:48:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:48:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:48:32 INFO - Running setup.py install for mozfile 06:48:32 INFO - Running setup.py install for mozhttpd 06:48:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:48:32 INFO - Running setup.py install for mozinfo 06:48:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:48:32 INFO - Running setup.py install for mozInstall 06:48:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:48:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:48:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:48:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:48:32 INFO - Running setup.py install for mozleak 06:48:32 INFO - Running setup.py install for mozlog 06:48:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:48:32 INFO - Running setup.py install for moznetwork 06:48:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:48:33 INFO - Running setup.py install for mozprocess 06:48:33 INFO - Running setup.py install for mozprofile 06:48:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:48:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:48:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:48:33 INFO - Running setup.py install for mozrunner 06:48:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:48:33 INFO - Running setup.py install for mozscreenshot 06:48:33 INFO - Running setup.py install for moztest 06:48:33 INFO - Running setup.py install for mozversion 06:48:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:48:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:48:33 INFO - Cleaning up... 06:48:34 INFO - Return code: 0 06:48:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48: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')]} 06:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48: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')]} 06:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7ebdd4ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044e00>, '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 0x109eaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a03fb20>, '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.NR8iTrYYtn/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.GKpv4XcW1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 06:48:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:48:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NR8iTrYYtn/Render', 06:48:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:34 INFO - 'HOME': '/Users/cltbld', 06:48:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:34 INFO - 'LOGNAME': 'cltbld', 06:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:34 INFO - 'MOZ_NO_REMOTE': '1', 06:48:34 INFO - 'NO_EM_RESTART': '1', 06:48:34 INFO - 'PAGER': '/bin/cat', 06:48:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:34 INFO - 'PWD': '/builds/slave/test', 06:48:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:34 INFO - 'SHELL': '/bin/bash', 06:48:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GKpv4XcW1Q/Listeners', 06:48:34 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 06:48:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:34 INFO - 'USER': 'cltbld', 06:48:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:34 INFO - 'XPC_FLAGS': '0x0', 06:48:34 INFO - 'XPC_SERVICE_NAME': '0', 06:48:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:48:34 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-0spyWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:48:34 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-ugzCIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:48:34 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-fO64dk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:48:34 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-LfD0A3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:48:34 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-UmLRt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:48:34 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)) 06:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:48:34 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-RRvr0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:48:34 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)) 06:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:48:34 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Z6DYeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:48:35 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-q3NrQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:48:35 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-d7PiuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:48:35 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-dQTzaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:48:35 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-DF7h2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:48:35 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Gar_Lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:48:35 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-ws1Tj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:48:35 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-MwiwFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:48:35 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)) 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:35 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-bgPP9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:35 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)) 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:48:35 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-TJcBnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:48:35 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)) 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:48:35 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-iCy3y9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:48:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:38 INFO - Downloading blessings-1.6.tar.gz 06:48:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:48:38 INFO - Installing collected packages: blessings 06:48:38 INFO - Running setup.py install for blessings 06:48:38 INFO - Successfully installed blessings 06:48:38 INFO - Cleaning up... 06:48:38 INFO - Return code: 0 06:48:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:48:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:48:39 INFO - Reading from file tmpfile_stdout 06:48:39 INFO - Current package versions: 06:48:39 INFO - blessings == 1.6 06:48:39 INFO - blobuploader == 1.2.4 06:48:39 INFO - docopt == 0.6.1 06:48:39 INFO - manifestparser == 1.1 06:48:39 INFO - mozInstall == 1.12 06:48:39 INFO - mozcrash == 0.17 06:48:39 INFO - mozdebug == 0.1 06:48:39 INFO - mozdevice == 0.48 06:48:39 INFO - mozfile == 1.2 06:48:39 INFO - mozhttpd == 0.7 06:48:39 INFO - mozinfo == 0.9 06:48:39 INFO - mozleak == 0.1 06:48:39 INFO - mozlog == 3.1 06:48:39 INFO - moznetwork == 0.27 06:48:39 INFO - mozprocess == 0.22 06:48:39 INFO - mozprofile == 0.28 06:48:39 INFO - mozrunner == 6.11 06:48:39 INFO - mozscreenshot == 0.1 06:48:39 INFO - mozsystemmonitor == 0.0 06:48:39 INFO - moztest == 0.7 06:48:39 INFO - mozversion == 1.4 06:48:39 INFO - psutil == 3.1.1 06:48:39 INFO - requests == 1.2.3 06:48:39 INFO - wsgiref == 0.1.2 06:48:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7ebdd4ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044e00>, '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 0x109eaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a03fb20>, '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.NR8iTrYYtn/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.GKpv4XcW1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 06:48:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:48:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:48:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NR8iTrYYtn/Render', 06:48:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:39 INFO - 'HOME': '/Users/cltbld', 06:48:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:39 INFO - 'LOGNAME': 'cltbld', 06:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:39 INFO - 'MOZ_NO_REMOTE': '1', 06:48:39 INFO - 'NO_EM_RESTART': '1', 06:48:39 INFO - 'PAGER': '/bin/cat', 06:48:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:39 INFO - 'PWD': '/builds/slave/test', 06:48:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:39 INFO - 'SHELL': '/bin/bash', 06:48:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GKpv4XcW1Q/Listeners', 06:48:39 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 06:48:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:39 INFO - 'USER': 'cltbld', 06:48:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:39 INFO - 'XPC_FLAGS': '0x0', 06:48:39 INFO - 'XPC_SERVICE_NAME': '0', 06:48:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:48:39 INFO - Cleaning up... 06:48:39 INFO - Return code: 0 06:48:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:48:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7ebdd4ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044e00>, '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 0x109eaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a03fb20>, '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.NR8iTrYYtn/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.GKpv4XcW1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 06:48:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:48:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:48:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NR8iTrYYtn/Render', 06:48:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:39 INFO - 'HOME': '/Users/cltbld', 06:48:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:39 INFO - 'LOGNAME': 'cltbld', 06:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:39 INFO - 'MOZ_NO_REMOTE': '1', 06:48:39 INFO - 'NO_EM_RESTART': '1', 06:48:39 INFO - 'PAGER': '/bin/cat', 06:48:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:39 INFO - 'PWD': '/builds/slave/test', 06:48:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:39 INFO - 'SHELL': '/bin/bash', 06:48:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GKpv4XcW1Q/Listeners', 06:48:39 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 06:48:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:39 INFO - 'USER': 'cltbld', 06:48:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:39 INFO - 'XPC_FLAGS': '0x0', 06:48:39 INFO - 'XPC_SERVICE_NAME': '0', 06:48:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:39 INFO - Downloading/unpacking jsonschema 06:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:48:42 INFO - Downloading/unpacking functools32 (from jsonschema) 06:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:42 INFO - Downloading functools32-3.2.3-2.tar.gz 06:48:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:48:42 INFO - warning: no files found matching '*.txt' 06:48:42 INFO - no previously-included directories found matching 'build' 06:48:42 INFO - no previously-included directories found matching 'dist' 06:48:42 INFO - no previously-included directories found matching '.git*' 06:48:42 INFO - Installing collected packages: jsonschema, functools32 06:48:42 INFO - Running setup.py install for functools32 06:48:42 INFO - warning: no files found matching '*.txt' 06:48:42 INFO - no previously-included directories found matching 'build' 06:48:42 INFO - no previously-included directories found matching 'dist' 06:48:42 INFO - no previously-included directories found matching '.git*' 06:48:42 INFO - Successfully installed jsonschema functools32 06:48:42 INFO - Cleaning up... 06:48:42 INFO - Return code: 0 06:48:42 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:42 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 0x10a1a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7ebdd4ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044e00>, '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 0x109eaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a03fb20>, '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.NR8iTrYYtn/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.GKpv4XcW1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 06:48:42 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 06:48:42 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 06:48:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NR8iTrYYtn/Render', 06:48:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:42 INFO - 'HOME': '/Users/cltbld', 06:48:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:42 INFO - 'LOGNAME': 'cltbld', 06:48:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:42 INFO - 'MOZ_NO_REMOTE': '1', 06:48:42 INFO - 'NO_EM_RESTART': '1', 06:48:42 INFO - 'PAGER': '/bin/cat', 06:48:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:42 INFO - 'PWD': '/builds/slave/test', 06:48:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:42 INFO - 'SHELL': '/bin/bash', 06:48:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GKpv4XcW1Q/Listeners', 06:48:42 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 06:48:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:42 INFO - 'USER': 'cltbld', 06:48:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:42 INFO - 'XPC_FLAGS': '0x0', 06:48:42 INFO - 'XPC_SERVICE_NAME': '0', 06:48:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:43 INFO - Downloading/unpacking flake8 06:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:48:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:46 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:48:46 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:46 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:48:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:48:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:48:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:48:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:48:46 INFO - no previously-included directories found matching 'docs/_build' 06:48:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:48:47 INFO - Running setup.py install for pep8 06:48:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:48:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:48:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:48:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:48:47 INFO - no previously-included directories found matching 'docs/_build' 06:48:47 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:48:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:48:47 INFO - Cleaning up... 06:48:47 INFO - Return code: 0 06:48:47 INFO - Running post-action listener: _resource_record_post_action 06:48:47 INFO - Running post-action listener: _start_resource_monitoring 06:48:47 INFO - Starting resource monitoring. 06:48:47 INFO - ##### 06:48:47 INFO - ##### Running install step. 06:48:47 INFO - ##### 06:48:47 INFO - Running pre-action listener: _resource_record_pre_action 06:48:47 INFO - Running main action method: install 06:48:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:48:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:48:47 INFO - Reading from file tmpfile_stdout 06:48:47 INFO - Detecting whether we're running mozinstall >=1.0... 06:48:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:48:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:48:47 INFO - Reading from file tmpfile_stdout 06:48:47 INFO - Output received: 06:48:47 INFO - Usage: mozinstall [options] installer 06:48:47 INFO - Options: 06:48:47 INFO - -h, --help show this help message and exit 06:48:47 INFO - -d DEST, --destination=DEST 06:48:47 INFO - Directory to install application into. [default: 06:48:47 INFO - "/builds/slave/test"] 06:48:47 INFO - --app=APP Application being installed. [default: firefox] 06:48:47 INFO - mkdir: /builds/slave/test/build/application 06:48:47 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'] 06:48:47 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 06:49:19 INFO - Reading from file tmpfile_stdout 06:49:19 INFO - Output received: 06:49:19 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 06:49:19 INFO - Running post-action listener: _resource_record_post_action 06:49:19 INFO - ##### 06:49:19 INFO - ##### Running run-tests step. 06:49:19 INFO - ##### 06:49:19 INFO - Running pre-action listener: _resource_record_pre_action 06:49:19 INFO - Running main action method: run_tests 06:49:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:49:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:49:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:49:19 INFO - 2016-06-06 06:49:19.457 screenresolution[2541:28199] starting screenresolution argv=screenresolution get 06:49:19 INFO - 2016-06-06 06:49:19.460 screenresolution[2541:28199] Display 0: 1600x1200x32@60 06:49:19 INFO - 2016-06-06 06:49:19.467 screenresolution[2542:28201] starting screenresolution argv=screenresolution list 06:49:19 INFO - Available Modes on Display 0 06:49:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:49:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:49:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:49:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:49:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:49:19 INFO - Intel Iris: 06:49:19 INFO - Chipset Model: Intel Iris 06:49:19 INFO - Type: GPU 06:49:19 INFO - Bus: Built-In 06:49:19 INFO - VRAM (Dynamic, Max): 1536 MB 06:49:19 INFO - Vendor: Intel (0x8086) 06:49:19 INFO - Device ID: 0x0a2e 06:49:19 INFO - Revision ID: 0x0009 06:49:19 INFO - Displays: 06:49:19 INFO - Display: 06:49:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:49:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:49:19 INFO - Display Serial Number: 1600x1200 60 06:49:19 INFO - Main Display: Yes 06:49:19 INFO - Mirror: Off 06:49:19 INFO - Online: Yes 06:49:19 INFO - Rotation: Supported 06:49:19 INFO - Return code: 0 06:49:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:49:19 INFO - Python 2.7.3 06:49:19 INFO - Return code: 0 06:49:19 INFO - grabbing minidump binary from tooltool 06:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:49:19 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 0x10a044e00>, '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 0x109eaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a03fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:49:19 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 06:49:19 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 06:49:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:49:19 INFO - Return code: 0 06:49:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:49:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:49:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:49:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:49:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:49:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:49:20 INFO - Return code: 0 06:49:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0386', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:49:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0386 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:49:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NR8iTrYYtn/Render', 06:49:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:49:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:49:20 INFO - 'HOME': '/Users/cltbld', 06:49:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:49:20 INFO - 'LOGNAME': 'cltbld', 06:49:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:49:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:49:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:20 INFO - 'MOZ_NO_REMOTE': '1', 06:49:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:49:20 INFO - 'NO_EM_RESTART': '1', 06:49:20 INFO - 'PAGER': '/bin/cat', 06:49:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:49:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:20 INFO - 'PWD': '/builds/slave/test', 06:49:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:49:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:49:20 INFO - 'SHELL': '/bin/bash', 06:49:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GKpv4XcW1Q/Listeners', 06:49:20 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 06:49:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:49:20 INFO - 'USER': 'cltbld', 06:49:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:49:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:49:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:20 INFO - 'XPC_FLAGS': '0x0', 06:49:20 INFO - 'XPC_SERVICE_NAME': '0', 06:49:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:49:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0386', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:49:21 INFO - mozversion application_buildid: 20160606010529 06:49:21 INFO - mozversion application_changeset: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 06:49:21 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:49:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:49:21 INFO - mozversion application_name: Firefox 06:49:21 INFO - mozversion application_remotingname: firefox-dev 06:49:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:49:21 INFO - mozversion application_vendor: Mozilla 06:49:21 INFO - mozversion application_version: 48.0a2 06:49:21 INFO - mozversion platform_buildid: 20160606010529 06:49:21 INFO - mozversion platform_changeset: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 06:49:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:49:21 INFO - mozversion platform_version: 48.0a2 06:49:21 INFO - using testdate: 1465220961 06:49:21 INFO - actual date: 1465220961 06:49:21 INFO - starting webserver on 'localhost:56161' 06:49:21 INFO - SUITE-START | Running 2 tests 06:49:21 INFO - TEST-START | dromaeo_css 06:49:21 INFO - operating with platform_type : mac_ 06:49:21 INFO - Initialising browser for dromaeo_css test... 06:49:21 INFO - TEST-INFO | started process 2560 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp5h0phz/profile http://localhost:56161/getInfo.html) 06:49:23 INFO - PROCESS | 2560 | __metrics Screen width/height:1600/1200 06:49:23 INFO - PROCESS | 2560 | colorDepth:24 06:49:23 INFO - PROCESS | 2560 | Browser inner width/height: 1024/689 06:49:23 INFO - PROCESS | 2560 | __metrics 06:49:23 INFO - PROCESS | 2560 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:49:23 ERROR - PROCESS | 2560 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:49:24 INFO - TEST-INFO | 2560: exit 0 06:49:24 INFO - Browser initialized. 06:49:24 INFO - Running cycle 1/1 for dromaeo_css test... 06:49:24 INFO - TEST-INFO | started process 2562 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp5h0phz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:49:36 INFO - PROCESS | 2562 | RSS: Main: 211369984 06:49:36 INFO - PROCESS | 2562 | 06:50:27 INFO - PROCESS | 2562 | 1465221027421 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] 06:52:38 INFO - PROCESS | 2562 | Cycle 1(1): loaded http://localhost:56161/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56161/tests/dromaeo/cssquery-ext.html) 06:52:39 INFO - PROCESS | 2562 | RSS: Main: 346927104 06:52:39 INFO - PROCESS | 2562 | 06:55:42 INFO - PROCESS | 2562 | Cycle 1(1): loaded http://localhost:56161/tests/dromaeo/cssquery-ext.html (next: http://localhost:56161/tests/dromaeo/cssquery-jquery.html) 06:55:43 INFO - PROCESS | 2562 | RSS: Main: 356990976 06:55:43 INFO - PROCESS | 2562 | 06:58:45 INFO - PROCESS | 2562 | Cycle 1(1): loaded http://localhost:56161/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56161/tests/dromaeo/cssquery-mootools.html) 06:58:45 INFO - PROCESS | 2562 | RSS: Main: 357806080 06:58:45 INFO - PROCESS | 2562 | 07:01:48 INFO - PROCESS | 2562 | Cycle 1(1): loaded http://localhost:56161/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56161/tests/dromaeo/cssquery-prototype.html) 07:01:48 INFO - PROCESS | 2562 | RSS: Main: 357507072 07:01:48 INFO - PROCESS | 2562 | 07:04:51 INFO - PROCESS | 2562 | Cycle 1(1): loaded http://localhost:56161/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56161/tests/dromaeo/cssquery-yui.html) 07:04:52 INFO - PROCESS | 2562 | RSS: Main: 321437696 07:04:52 INFO - PROCESS | 2562 | 07:07:55 INFO - PROCESS | 2562 | Cycle 1(1): loaded http://localhost:56161/tests/dromaeo/cssquery-yui.html (next: http://localhost:56161/tests/dromaeo/cssquery-dojo.html) 07:07:55 INFO - PROCESS | 2562 | RSS: Main: 368873472 07:07:55 INFO - PROCESS | 2562 | 07:07:55 INFO - PROCESS | 2562 | __start_tp_report 07:07:55 INFO - PROCESS | 2562 | _x_x_mozilla_page_load 07:07:55 INFO - PROCESS | 2562 | _x_x_mozilla_page_load_details 07:07:55 INFO - PROCESS | 2562 | |i|pagename|runs| 07:07:55 INFO - PROCESS | 2562 | |0;dojo.html;3282.2778988622495;3302.1744563859033;3314.2377253231753;3329.6004479462467;3387.8983630491084;5631.1271752878265;5632.380438151805;5641.633293835903;5643.5203007744285;5651.350094739107;5623.297087864017;5650.9152362714485;5680.545556355492;5680.715964201794;5689.431056894308;4315.611594956454;5133.358330208724;5199.860620239929;5207.739613019353;5274.393444753845;4349.673774466912;5264.92102618461;5279.128943724281;5282.07563676357;5292.179712144604;5209.739513024352;5248.8687782805355;5266.81566145185;5270.683758974455;5281.36623605166;5266.657667251617;5283.9698001952065;5289.603279754034;5289.788408463657;5310.867228319285;5080.618953578496;5117.590592752571;5121.974390128025;5181.922271165936;5190.766415511311;4549.658775591844;5569.498745112919;5595.692236926968;5604.579656525777;5626.746796394172;6181.84545386369;6219.378062193789;6221.4089661482085;6235.064740288947;6241.563090583661;6103.176071230416;6196.473299769525;6285.748570057192;6302.96848515759;6335.208098987627;5782.248307720011;5802.274715660543;5815.9709201453725;5816.272965879265;5836.708164591753;6322.873542529123;6334.208223972004;6334.651594162273;6336.714847831859;6361.173047503795;6038.9396106038375;6105.277797093309;6111.786087487006;6172.135900973867;6229.53278504114;6271.874562508724;6292.905606415816;6298.338674439209;6306.148669929593;6313.179286692701;6185.257769067741;6225.3774622538285;6246.593971391845;6250.781222657185;6259.812205633747;6095.4818840398175;6144.740328232791;6167.198264225622;6183.072539119078;6255.655938923312;4809;4906.852794416173;4912.557869791736;4921.8523444296725;4925.9901720148;4041.3533834586915;4264.829406823755;4403.185410699031;4409.074094440203;4410.911781764346;5654.802081927113;5655.575831812631;5686.061799802987;5693.850780809685;5706;6123.5876691309595;6191.4118158774845;6308.810735677937;6310.652914089769;6318.684065796691;5686.658800471985;5733.770649173986;5735.082386818089;5743.4543718346695;5745.1382292656435;5317.6277660564165;5442.91835622466;5446.754896029688;5462.016836969304;5471.863203419866;6205.627662340274;6275.403836635513;6359.936400635935;6368.363163683594;6398.1362516059735;6277.874442511033;6286.96265116269;6290.119383286435;6294.842628934313;6325.841853953504;6306.306306306395;6349.777757778455;6360.713767880364;6365.90451143224;6367.299597044229;6069.757209711562;6139.84650383744;6142.314611233224;6212.316645169119;6263.123162757127;6224.190855188796;6274.6548939808745;6280.434760871543;6292.433680968735;6324.778632747832;6157.4458298753325;6163.691815409301;6165.691715414301;6190.288116866618;6192.287886893065;5932.555058370725;6090.725917333642;6090.756369745338;6095.2685677718955;6096.542759292982;4628.282616194495;5882.764689412375;5890.9116363253725;5962.850928726816;5993.910091348546;6115.969420152871;6125.234345706786;6133.49188346556;6138.882085680433;6158.476529495043;5799.884002319845;5895.145203945318;5949.881002379841;5951.434613711691;5958.731857066529 07:07:55 INFO - PROCESS | 2562 | |1;ext.html;518.5462720119892;534.2147043845547;581.3546962871212;587.632729544035;611.1901730207474;31207.68792312079;31883.68116318836;38778.80610596947;39476.407853882214;39524;42548.57451425485;43160.56839431605;43201.56798432015;43249.56750432495;43272;8311.83376332473;9422.952885235563;9636.566354514063;9648.228141748643;9648.855267170962;8527.44571602847;11503.597374091909;11713.180077394543;11722.589709360174;11727.120465965045;11456.198066135374;11537.365444900528;11539.942300288487;11542.07663386927;11548.249363791369;8522.275606573448;11226.494807733672;11296.265742726737;11584.47869845859;11782.705432364173;11126.443677816076;11223.214374993753;11240.550377984871;11246.943765281121;11267;39220.60779392227;39635.81092567199;39666.61166859146;39693.80153099245;39838.80080599579;3242.91892702682;3350.1792060945227;3411.306042885004;3706.3513885070274;3912.43269725891;25881.611775823378;26450.735492645214;27447.90208391662;27902.441951160865;28141.57787633187;2715.389037466574;2766.9308267293277;2787.5261205595098;2825.519661657523;2839.1482555233347;351392;353712;353895;354299.4570054318;355648.2217588921;60356;63955;64825.35174648288;64851;64910.35089649043;68066.65966670234;68414.65792671005;71323;72649;72790.27209727834;15355.462558810388;17589.7361539577;17845.84002039863;17851.73222401665;17859.017754023655;12407.503699852085;12687.873121268669;12778.680532986564;13496.797548036788;13806.309684515734;19894.90052549728;20139.19443222284;20153.09311081005;20168.596628067357;20236.898815506123;6920.342567456084;8062.556559389172;8250.298724608356;8264.710735124241;8306.127856575093;961.6297725375641;1029.6910926721953;1067.4395942130443;1110.0952723530459;1158.4207896051973;26942.32644183911;30070.54894176589;30518.69481305203;30781.029022169616;31241;1081.0594782539083;1093.136422226448;1151.7984352738238;1159.4492616007499;1174.6123779152858;15250.161241131844;15533.611659708598;15824.683506330039;15880.52358429249;16019.279132439067;620977;622821;626504;628750;629399;63286.68356658187;63812.042819357746;63908;64041.67979160074;64105.03842442367;1395.2047333020082;1420.978685319721;1538.8691961183424;1555.1368990210315;1561.6252099496267;23654.52690946137;28680.713192867803;28991.710082898902;29507.967221147155;29671.55492667655;51192;51708;51749.223761642854;51904.961900761016;51912.70218244574;429824;432862;432929;434522;434984.8250758726;23341;27181.72818271792;27447.313817154733;27712.58431123575;27788.444231115667;40975;41732.58267417287;42395.1520969585;44013;44546.77726611346;34317.82841085779;34540.13649658779;35454.46818297779;36344.636553634125;36423.635763643084;34822.82588587041;35281.47077793784;35329.82335088308;35852.462213067345;36022.63977360193;37122;37480.81259593685;38042;38056.23887522179;38282.80858595689;31280.21799455032;32725.34549309048;32788.180295492806;32804.01587952365;32859;24086.638700419157;24388.390290242885;24553;24853.25440236796;24883.253502394957 07:07:55 INFO - PROCESS | 2562 | |2;jquery.html;382.47409811509175;396.7797872180938;401.60642570281107;402.55115546166024;409.844259181511;15165;18450;20254.6961795573;20977;20982.16071357146;21007.567999600386;21262.787372126313;22214.777852221472;22333.888330558326;22345.10619575215;5950.523958083354;5972.970135149319;6080.787172448965;6190.876182476371;6210.068489726532;4259.936100958473;6158.107074474198;6159.291681456622;6204.945159322903;6519.739210431577;5970.79102231422;6629.734810607594;6796.524243302971;6806.727730890759;6811.284815094418;4823.589994850442;6103.115048318014;6114.724837382329;6768.984652302145;6778.254392016875;5872.5889187756875;5896.056630939029;5899.646021238739;5914.2311499504785;5921.911171332434;13439;13745.24401157936;14069.366878490493;14075.788863167061;14099.506517271846;2369.218158007853;2399.040383846458;2504.8246622736415;2858.6283783108065;2863.985680071586;10095.747606309827;10463.84304235437;13766.174029558259;14848.554543363716;14871.479498217619;1729.9135043247786;1750.2561411400231;1763.2946821271466;1786.1337251433044;1815.636872625467;110703;115758;118355;118466.22300665497;119437;11685.649430517098;11782.882171178178;11903.047756179658;11988.64034078979;12036.63890083299;13859.168449893039;14375.496857609933;14594.978351515505;15276.388944442098;15340.079595224322;8914.465132092095;8985;9002.279817614575;9008.009118996904;9112.362134650644;9001.459912405277;9017.413868098683;9038.774030649287;9072.319576031825;9139.177474027369;4681.836135735233;4718.127146477913;4720.929186062212;4721.976390118028;4765.189917713997;1720.4838548435669;1745.7381392790928;1951.5999220159833;2036.287299445212;2050.1389416445127;902.2105657549644;906.7506435730227;910.2081189365294;976.3799987008159;1011.6155860772859;19372.4188274352;20117.798822011886;20178.991050447417;21670.783292166878;21716.782832171793;951.7810903492098;965.3483898368573;990.6087095597198;999.1107913956585;1041.1358572384902;11225.326480411202;11313.648012069028;11327.773444531063;11328.150388720878;11381.829272560919;592137;616903;622229;636433;640826;93497;124379.3781031089;124475.3776231113;129378;132478.01282980762;997.4190907411834;1003.287665757306;1015.2030655935204;1016.4511163971521;1037.5849660135855;18286.354228119002;18859.774114682503;19106.617867642846;19144.521386965436;19438.319658811874;37491.250174996894;49270;52368;52777.208341875674;55826.72086639541;65767;68813.65593172002;72210.27789722246;72614.54770904446;77938.83091753742;6879.6216208107035;7901.446898717035;8254.876176857471;8302.294304984143;8329.54187519668;12051.156419050581;12129.939350303192;12344.321062341658;12493.500259989498;12599.496020159457;10731.946340268249;10996.395198264172;11024.614138505434;11043.337399756041;11076.501557430096;10420.218483613608;10468.371897686164;10493.213009024506;10805.945970270099;10875.891241087487;10714.678559643224;10777.515011824644;10983.450827458435;11170.832437513282;11260;7970;8188.222118898695;8233.79415514617;8234.464759790595;8276;9446.574904129193;9689.41863488193;10447.582096716047;10682.786344273227;10993.835092473779 07:07:55 INFO - PROCESS | 2562 | |3;mootools.html;1402.263811498963;1446.3925151436408;1605.6226786705126;1806.4670922077985;1997.570522337698;7844.980152580164;8519.148085191486;8726.694565690203;8738.169873861973;8738.563071846414;8537.871931921027;8547.957260213705;8676.132386761321;8698.826023479527;8709;2162.6323525000707;2442.0842184180888;2474.0845887021737;2475.344033831027;2477.071098338123;2221.9000144993515;2228.565429741201;2230.4981379189635;2434.573949558829;2488.208489887278;2423.6970378702663;2427.2960841356075;2465.852048877064;2467.7655622715815;2469.394998225437;2413.650020746991;2439.926802195937;2448.6939132608422;2450.9509809803703;2451.019592163131;1943.5821298420908;2180.1824315881545;2189.43074800553;2417.9274621761374;2476.219990702941;8369.748907532723;8466.280366168883;8466.576671166416;8476.449030812977;8483.448575842613;5186.662866913639;5286.392064912546;5291.629585928987;5299.894002119936;5309.4425085365865;3215.4051500472256;3254.739620830328;3258.201740573551;3368.59576850777;3432.622411534729;1065.2170654568886;1083.025277250473;1083.9078678312353;1085.1535802074395;1087.5812812067331;11764.058875289957;11966.940165299206;11984.161108722394;11996.820047699204;12004.219725717887;3871.728978971445;4668.369770080995;4795.736234507136;4843.418789745253;4850.296706977469;8416.326693864476;10093.343932644355;10093.394396336244;10097;10119.696409107739;5332.866678332995;5336.653117547425;5380.085385484477;5387.138057910715;5392.487713667196;4224.619784219435;4857.489963553847;4867.099586576494;4868.902621947612;4871.098846713369;3769.4911186989953;3830.4637350770913;3898.142408670088;3899.161680238762;4098.159877225162;1326.6086504481202;1337.6120924931856;1350.4530665080658;1354.512375544803;1362.455017992791;914.1909410172045;919.6275508419101;944.1031020530386;945.2721404518483;960.9087136722001;5143.948560514422;5768.394318596571;5848.327442344185;5852.882942341129;5953.077273022688;447.0634021724482;450.6890245730435;458.136412861758;465.4763391184917;474.42120612852267;3867.3038853006165;3921.7254792164276;3970.82131304092;4041.1715598302276;4049.230646177276;10117.241206909512;10483.056524912641;10491.16070714341;10609.84085238722;10617.734556636145;9787.119159275557;10229.59081636741;10255.487225638688;10396.48017599132;10399.94800025995;7786.610669466618;7874.133845277015;8134.389920755967;8139.715109971123;8226.465279756796;4748.952510474989;5703.486686198262;5807;5852.912206316993;5878.617889837147;9007.864882026644;9217;9369.765755856159;9469.431834089977;9470.95264523673;9620.422774633544;9688.564014619218;9708.417494950325;9760.804783904425;9766.706998790047;7386.667599957907;7512.924870751222;7520.247975202436;7595.886061708969;7618.085829700472;7196.136463624399;7388.556686598821;7396.815079623052;7984.840303193788;8029.156938521372;6622.867542649024;6848.280930502425;7164.641767911479;7347.5224110432655;7529.548227106391;6525;6760.459163266829;6834.45324374039;7068.71725130989;7150;6895.103636527219;6910.343517365842;6967.442604591519;6968.477364197807;6973.442124629916;4640.164770341371;4782.402199725035;4915.615926275682;4978.5270399312;4995.075910956485;4836.830710925101;4884.82903098404;4945.703257804399;4981.452040275639;4989.151844186425 07:07:55 INFO - PROCESS | 2562 | |4;prototype.html;323.46951000359405;325.277883099519;331.40016570008277;334.79912052768327;336.4499044062956;6635.86728265435;6654.96672516638;6660.50046246531;6683.365080317368;6692.464602831774;6534.15056042715;6607.966960165192;6670.966645166767;6680.565763225387;6691.6319602421845;4724.669273150881;4823.493533179018;4831.275308703687;4920.75396230187;4929.2606109083745;4885.902281954359;4956.950430495685;4984.87537811554;4987.226979818116;4990.3762029746285;4881.023795240948;4897.608191344684;4914.778834952435;4917.901641967176;4939.753012349386;4677.812887484513;4797.4003249593625;4800;4895.922896962663;4907.460179380265;3430.6054803697643;4202.159568086365;4207.978960105211;4225.112726327475;4235.618794308527;6352.555321127523;6373.553851230416;6376.202974628171;6389.073584330293;6407.199100112486;5825.592208545403;5828.5337173026055;5829.5919285650025;5836.941630583682;5851.180834683148;6794.3545363190415;7064.399526040293;7065.82335441618;7067.22260551339;7088.326608972139;3937.3109705801658;4738.502457241974;4738.857834264943;4739.14695354836;4754.524547545252;7348.265173482662;7526.397888168988;7527.172011078777;7531.736389226406;7548.849023019508;4805.447373552016;4837.806487740521;4839.782209800567;4840.2255639097575;4844.927326090112;4893.608511319086;4905.509449055137;4907.55831975124;4910.459849416561;4913.606911447076;5602.607817452821;5812.622179558315;5831.591788574844;5834.416558344132;5837.7956771513645;7124.216336203013;7130.964345178241;7172.677229524684;7178.066851309296;7190.64046797658;1667.2164082881027;1706.6671998960085;1707.2061491406555;1711.3154738104608;1714.7170716831583;913.3013244867682;1035.4770840725384;1050.9316894401838;1083.8320060390652;1110.8944650258213;6534.248561415498;6727.9327206728285;6732.831679207961;6750.83122921921;6754.223264324665;7454.776356709307;7471.514351567131;7471.887921681179;7475.401967842559;7478.626068696543;5589.35722391922;5669.951059054089;6388.872222555523;6708;6713.295104014008;3187.6015498062743;3779.7543159694533;3925.1953349563273;3937.192875460581;3945.980270098631;7759.030121234846;7781.027371578552;7791.091182660284;7799.142094369615;7809.414293927978;7578.317951384402;7655.770326890202;7671.619108560401;7676.923230767732;7677.923220767832;6619.569727967667;7721.227877212234;7777.144514103444;7806.453548251568;7810;7066.434685225273;7383.630818459163;7418.221086785809;7419.629018549159;7429.777106686808;7251.56490610565;7273.272672732896;7285.562866228043;7310.305520975498;7310.451716121206;7762.961185194038;7767.145613982344;7779.961100194463;7785;7806.258405451473;4825.806967721252;4842.539958703917;4845.636577256791;4852.126617208936;4858.416989961227;7083.397911177608;7365.116186057708;7367.889481657672;7371.3734332582335;7376.225496323023;5809.331926828299;7067.434605231672;7143.535670181422;7144.035555199982;7167.964160179165;7116.679749411185;7125.928740712527;7146.642667866482;7164.785056448315;7164.89252661221;6982.1272340957385;6988.40598549129;6991.825204369931;7000.194977577644;7013.228544860163;6892.794305822089;7136.464765142531;7150.392216661642;7153.928460715326;7165.283471652793;6767.323267673391;6963.1644202696;6967.825804354931;6979.825504362431;7007.299270072952 07:07:55 INFO - PROCESS | 2562 | |5;yui.html;703.5637904499208;705.4391758551951;734.3390948146671;765.8200322924115;795.5544894858878;13226;14271.857281427183;15218.847811521882;15234.009789363683;15254.999999999985;13791.241481718502;13866.653333666663;13877.930610346959;13882.93058534706;13885.930570347135;734.0787311923555;751.9661615227301;879.3756432932626;912.1289168843738;920.8756817829574;902.4224496322358;916.6974898283584;918.2745630951574;925.0703043441332;939.8355287824637;971.4656938683731;973.0318333258418;975.3124047546463;977.0034564743956;989.9506523085516;777.3392616276149;972.5817898303727;973.5521660036392;979.8187335342984;988.2785566536397;725.8076609698381;726.3281464645182;728.6538894025349;731.7950973727364;744.2222877093396;14846.851531484655;14910.478133265393;14923.850761492355;14928.552143435714;14931;12292.877071229352;12719;12755.703973284804;12932.224066556037;12998.773700594269;9879.983818042901;9927.062424230413;9947.403155810602;10348.586056557804;10399.376037437778;4038.3942408638645;4055.655269302113;4058.7767672777986;4131.5455299916985;4267.146570685875;66090.66954665244;66640.33399165011;66677;67417.3258267416;67428.98856517156;8968.148025937526;9205.953970230106;9294.90705092954;9440.197583205367;9980.800383992424;19202.61594768097;19334.033298335027;21138.47153821136;21167.835769032543;21176.89411552963;7430.256974302634;7463.365613922769;7511.737089201852;7513.32380085784;7689.769306920802;3888.7083468739957;3971.4638523799495;4157.314043182841;4157.750534967911;4443.933340999887;3569.214772749991;3624.1664417184197;3624.873129440457;3627.2020155565733;3629.219717760694;636.4280722276532;647.922249330074;656.7799787071289;656.9146011018537;663.5454713521253;589.4105894105894;594.5897330841792;607.4108115128319;608.3733754233147;623.7037407231619;7863.803404914808;8284.71003514874;8303.211194936472;8340.582970851554;8500.829983400297;588.3733823477983;591.6745789815584;600.1477901380051;618.77724019353;620.1380081686459;3698.2788356270808;3821.4458903458844;3823.2735780201674;3902.70729695274;3928.6071392860486;90972.6354104689;91478.54260728786;96137;96264;96332.51833740786;77275;79626.60186699145;80805.19194808094;82254;83058;72393.6380318095;72494.18764530824;72670;74241.5151696963;74256;7584.469087163846;7698.961505192438;7889.684412623431;7935.2064793520185;7965.203479651988;50739.49260507348;51462;52028.276889542874;52112;52784;68508.6574567124;69237.2690682737;69563;70187.29812701808;70855.29144708486;9723.222142228464;9884.901150988399;10123.7469063274;10213.642522511676;10511.054005139575;10668.786624267315;10804.891951080703;10809.027187553158;10947.83578246343;11227.326360418401;10365.118964887768;10573.154147668321;10630.25588208849;10659.94670026645;10675.359478431319;10218.744531386477;10278.897211027794;10421;10599.046085852311;10706.304090234422;10022.14811740981;10042.096211340722;10104.44425556572;10255.7436064099;10854;8653.17794809492;8695.435816795727;8748.387612867038;8931.19619234272;9025.232855207381;8822.470651760916;8839.16027977341;8878.245349145394;8897.688580899638;9007.324450666358 07:07:55 INFO - PROCESS | 2562 | __end_tp_report 07:07:55 INFO - PROCESS | 2562 | __start_cc_report 07:07:55 INFO - PROCESS | 2562 | _x_x_mozilla_cycle_collect,1247 07:07:55 INFO - PROCESS | 2562 | __end_cc_report 07:07:55 INFO - PROCESS | 2562 | __startTimestamp1465222075546__endTimestamp 07:07:55 INFO - PROCESS | 2562 | 07:07:55 INFO - PROCESS | 2562 | ------- Summary: start ------- 07:07:55 INFO - PROCESS | 2562 | Number of tests: 6 07:07:55 INFO - PROCESS | 2562 | 07:07:55 INFO - PROCESS | 2562 | [#0] dojo.html Cycles:165 Average:5724.70 Median:5951.43 stddev:683.67 (11.5%) stddev-sans-first:658.37 07:07:55 INFO - PROCESS | 2562 | Values: 3282.3 3302.2 3314.2 3329.6 3387.9 5631.1 5632.4 5641.6 5643.5 5651.4 5623.3 5650.9 5680.5 5680.7 5689.4 4315.6 5133.4 5199.9 5207.7 5274.4 4349.7 5264.9 5279.1 5282.1 5292.2 5209.7 5248.9 5266.8 5270.7 5281.4 5266.7 5284.0 5289.6 5289.8 5310.9 5080.6 5117.6 5122.0 5181.9 5190.8 4549.7 5569.5 5595.7 5604.6 5626.7 6181.8 6219.4 6221.4 6235.1 6241.6 6103.2 6196.5 6285.7 6303.0 6335.2 5782.2 5802.3 5816.0 5816.3 5836.7 6322.9 6334.2 6334.7 6336.7 6361.2 6038.9 6105.3 6111.8 6172.1 6229.5 6271.9 6292.9 6298.3 6306.1 6313.2 6185.3 6225.4 6246.6 6250.8 6259.8 6095.5 6144.7 6167.2 6183.1 6255.7 4809.0 4906.9 4912.6 4921.9 4926.0 4041.4 4264.8 4403.2 4409.1 4410.9 5654.8 5655.6 5686.1 5693.9 5706.0 6123.6 6191.4 6308.8 6310.7 6318.7 5686.7 5733.8 5735.1 5743.5 5745.1 5317.6 5442.9 5446.8 5462.0 5471.9 6205.6 6275.4 6359.9 6368.4 6398.1 6277.9 6287.0 6290.1 6294.8 6325.8 6306.3 6349.8 6360.7 6365.9 6367.3 6069.8 6139.8 6142.3 6212.3 6263.1 6224.2 6274.7 6280.4 6292.4 6324.8 6157.4 6163.7 6165.7 6190.3 6192.3 5932.6 6090.7 6090.8 6095.3 6096.5 4628.3 5882.8 5890.9 5962.9 5993.9 6116.0 6125.2 6133.5 6138.9 6158.5 5799.9 5895.1 5949.9 5951.4 5958.7 07:07:55 INFO - PROCESS | 2562 | 07:07:55 INFO - PROCESS | 2562 | [#1] ext.html Cycles:180 Average:62231.61 Median:3380.74 stddev:129210.91 (3822.0%) stddev-sans-first:129490.30 07:07:55 INFO - PROCESS | 2562 | Values: 518.5 534.2 581.4 587.6 611.2 31207.7 31883.7 38778.8 39476.4 39524.0 42548.6 43160.6 43201.6 43249.6 43272.0 8311.8 9423.0 9636.6 9648.2 9648.9 8527.4 11503.6 11713.2 11722.6 11727.1 11456.2 11537.4 11539.9 11542.1 11548.2 8522.3 11226.5 11296.3 11584.5 11782.7 11126.4 11223.2 11240.6 11246.9 11267.0 39220.6 39635.8 39666.6 39693.8 39838.8 3242.9 3350.2 3411.3 3706.4 3912.4 25881.6 26450.7 27447.9 27902.4 28141.6 2715.4 2766.9 2787.5 2825.5 2839.1 351392.0 353712.0 353895.0 354299.5 355648.2 60356.0 63955.0 64825.4 64851.0 64910.4 68066.7 68414.7 71323.0 72649.0 72790.3 15355.5 17589.7 17845.8 17851.7 17859.0 12407.5 12687.9 12778.7 13496.8 13806.3 19894.9 20139.2 20153.1 20168.6 20236.9 6920.3 8062.6 8250.3 8264.7 8306.1 961.6 1029.7 1067.4 1110.1 1158.4 26942.3 30070.5 30518.7 30781.0 31241.0 1081.1 1093.1 1151.8 1159.4 1174.6 15250.2 15533.6 15824.7 15880.5 16019.3 620977.0 622821.0 626504.0 628750.0 629399.0 63286.7 63812.0 63908.0 64041.7 64105.0 1395.2 1421.0 1538.9 1555.1 1561.6 23654.5 28680.7 28991.7 29508.0 29671.6 51192.0 51708.0 51749.2 51905.0 51912.7 429824.0 432862.0 432929.0 434522.0 434984.8 23341.0 27181.7 27447.3 27712.6 27788.4 40975.0 41732.6 42395.2 44013.0 44546.8 34317.8 34540.1 35454.5 36344.6 36423.6 34822.8 35281.5 35329.8 35852.5 36022.6 37122.0 37480.8 38042.0 38056.2 38282.8 31280.2 32725.3 32788.2 32804.0 32859.0 24086.6 24388.4 24553.0 24853.3 24883.3 07:07:55 INFO - PROCESS | 2562 | 07:07:55 INFO - PROCESS | 2562 | [#2] jquery.html Cycles:180 Average:34985.46 Median:22274.33 stddev:103348.30 (464.0%) stddev-sans-first:103605.56 07:07:55 INFO - PROCESS | 2562 | Values: 382.5 396.8 401.6 402.6 409.8 15165.0 18450.0 20254.7 20977.0 20982.2 21007.6 21262.8 22214.8 22333.9 22345.1 5950.5 5973.0 6080.8 6190.9 6210.1 4259.9 6158.1 6159.3 6204.9 6519.7 5970.8 6629.7 6796.5 6806.7 6811.3 4823.6 6103.1 6114.7 6769.0 6778.3 5872.6 5896.1 5899.6 5914.2 5921.9 13439.0 13745.2 14069.4 14075.8 14099.5 2369.2 2399.0 2504.8 2858.6 2864.0 10095.7 10463.8 13766.2 14848.6 14871.5 1729.9 1750.3 1763.3 1786.1 1815.6 110703.0 115758.0 118355.0 118466.2 119437.0 11685.6 11782.9 11903.0 11988.6 12036.6 13859.2 14375.5 14595.0 15276.4 15340.1 8914.5 8985.0 9002.3 9008.0 9112.4 9001.5 9017.4 9038.8 9072.3 9139.2 4681.8 4718.1 4720.9 4722.0 4765.2 1720.5 1745.7 1951.6 2036.3 2050.1 902.2 906.8 910.2 976.4 1011.6 19372.4 20117.8 20179.0 21670.8 21716.8 951.8 965.3 990.6 999.1 1041.1 11225.3 11313.6 11327.8 11328.2 11381.8 592137.0 616903.0 622229.0 636433.0 640826.0 93497.0 124379.4 124475.4 129378.0 132478.0 997.4 1003.3 1015.2 1016.5 1037.6 18286.4 18859.8 19106.6 19144.5 19438.3 37491.3 49270.0 52368.0 52777.2 55826.7 65767.0 68813.7 72210.3 72614.5 77938.8 6879.6 7901.4 8254.9 8302.3 8329.5 12051.2 12129.9 12344.3 12493.5 12599.5 10731.9 10996.4 11024.6 11043.3 11076.5 10420.2 10468.4 10493.2 10805.9 10875.9 10714.7 10777.5 10983.5 11170.8 11260.0 7970.0 8188.2 8233.8 8234.5 8276.0 9446.6 9689.4 10447.6 10682.8 10993.8 07:07:55 INFO - PROCESS | 2562 | 07:07:55 INFO - PROCESS | 2562 | [#3] mootools.html Cycles:180 Average:5574.11 Median:4870.00 stddev:3117.89 (64.0%) stddev-sans-first:3110.87 07:07:55 INFO - PROCESS | 2562 | Values: 1402.3 1446.4 1605.6 1806.5 1997.6 7845.0 8519.1 8726.7 8738.2 8738.6 8537.9 8548.0 8676.1 8698.8 8709.0 2162.6 2442.1 2474.1 2475.3 2477.1 2221.9 2228.6 2230.5 2434.6 2488.2 2423.7 2427.3 2465.9 2467.8 2469.4 2413.7 2439.9 2448.7 2451.0 2451.0 1943.6 2180.2 2189.4 2417.9 2476.2 8369.7 8466.3 8466.6 8476.4 8483.4 5186.7 5286.4 5291.6 5299.9 5309.4 3215.4 3254.7 3258.2 3368.6 3432.6 1065.2 1083.0 1083.9 1085.2 1087.6 11764.1 11966.9 11984.2 11996.8 12004.2 3871.7 4668.4 4795.7 4843.4 4850.3 8416.3 10093.3 10093.4 10097.0 10119.7 5332.9 5336.7 5380.1 5387.1 5392.5 4224.6 4857.5 4867.1 4868.9 4871.1 3769.5 3830.5 3898.1 3899.2 4098.2 1326.6 1337.6 1350.5 1354.5 1362.5 914.2 919.6 944.1 945.3 960.9 5143.9 5768.4 5848.3 5852.9 5953.1 447.1 450.7 458.1 465.5 474.4 3867.3 3921.7 3970.8 4041.2 4049.2 10117.2 10483.1 10491.2 10609.8 10617.7 9787.1 10229.6 10255.5 10396.5 10399.9 7786.6 7874.1 8134.4 8139.7 8226.5 4749.0 5703.5 5807.0 5852.9 5878.6 9007.9 9217.0 9369.8 9469.4 9471.0 9620.4 9688.6 9708.4 9760.8 9766.7 7386.7 7512.9 7520.2 7595.9 7618.1 7196.1 7388.6 7396.8 7984.8 8029.2 6622.9 6848.3 7164.6 7347.5 7529.5 6525.0 6760.5 6834.5 7068.7 7150.0 6895.1 6910.3 6967.4 6968.5 6973.4 4640.2 4782.4 4915.6 4978.5 4995.1 4836.8 4884.8 4945.7 4981.5 4989.2 07:07:55 INFO - PROCESS | 2562 | 07:07:55 INFO - PROCESS | 2562 | [#4] prototype.html Cycles:180 Average:5852.61 Median:6675.77 stddev:1872.22 (28.0%) stddev-sans-first:1830.90 07:07:55 INFO - PROCESS | 2562 | Values: 323.5 325.3 331.4 334.8 336.4 6635.9 6655.0 6660.5 6683.4 6692.5 6534.2 6608.0 6671.0 6680.6 6691.6 4724.7 4823.5 4831.3 4920.8 4929.3 4885.9 4957.0 4984.9 4987.2 4990.4 4881.0 4897.6 4914.8 4917.9 4939.8 4677.8 4797.4 4800.0 4895.9 4907.5 3430.6 4202.2 4208.0 4225.1 4235.6 6352.6 6373.6 6376.2 6389.1 6407.2 5825.6 5828.5 5829.6 5836.9 5851.2 6794.4 7064.4 7065.8 7067.2 7088.3 3937.3 4738.5 4738.9 4739.1 4754.5 7348.3 7526.4 7527.2 7531.7 7548.8 4805.4 4837.8 4839.8 4840.2 4844.9 4893.6 4905.5 4907.6 4910.5 4913.6 5602.6 5812.6 5831.6 5834.4 5837.8 7124.2 7131.0 7172.7 7178.1 7190.6 1667.2 1706.7 1707.2 1711.3 1714.7 913.3 1035.5 1050.9 1083.8 1110.9 6534.2 6727.9 6732.8 6750.8 6754.2 7454.8 7471.5 7471.9 7475.4 7478.6 5589.4 5670.0 6388.9 6708.0 6713.3 3187.6 3779.8 3925.2 3937.2 3946.0 7759.0 7781.0 7791.1 7799.1 7809.4 7578.3 7655.8 7671.6 7676.9 7677.9 6619.6 7721.2 7777.1 7806.5 7810.0 7066.4 7383.6 7418.2 7419.6 7429.8 7251.6 7273.3 7285.6 7310.3 7310.5 7763.0 7767.1 7780.0 7785.0 7806.3 4825.8 4842.5 4845.6 4852.1 4858.4 7083.4 7365.1 7367.9 7371.4 7376.2 5809.3 7067.4 7143.5 7144.0 7168.0 7116.7 7125.9 7146.6 7164.8 7164.9 6982.1 6988.4 6991.8 7000.2 7013.2 6892.8 7136.5 7150.4 7153.9 7165.3 6767.3 6963.2 6967.8 6979.8 7007.3 07:07:55 INFO - PROCESS | 2562 | 07:07:55 INFO - PROCESS | 2562 | [#5] yui.html Cycles:180 Average:18018.95 Median:33651.94 stddev:25580.41 (76.0%) stddev-sans-first:25619.13 07:07:55 INFO - PROCESS | 2562 | Values: 703.6 705.4 734.3 765.8 795.6 13226.0 14271.9 15218.8 15234.0 15255.0 13791.2 13866.7 13877.9 13882.9 13885.9 734.1 752.0 879.4 912.1 920.9 902.4 916.7 918.3 925.1 939.8 971.5 973.0 975.3 977.0 990.0 777.3 972.6 973.6 979.8 988.3 725.8 726.3 728.7 731.8 744.2 14846.9 14910.5 14923.9 14928.6 14931.0 12292.9 12719.0 12755.7 12932.2 12998.8 9880.0 9927.1 9947.4 10348.6 10399.4 4038.4 4055.7 4058.8 4131.5 4267.1 66090.7 66640.3 66677.0 67417.3 67429.0 8968.1 9206.0 9294.9 9440.2 9980.8 19202.6 19334.0 21138.5 21167.8 21176.9 7430.3 7463.4 7511.7 7513.3 7689.8 3888.7 3971.5 4157.3 4157.8 4443.9 3569.2 3624.2 3624.9 3627.2 3629.2 636.4 647.9 656.8 656.9 663.5 589.4 594.6 607.4 608.4 623.7 7863.8 8284.7 8303.2 8340.6 8500.8 588.4 591.7 600.1 618.8 620.1 3698.3 3821.4 3823.3 3902.7 3928.6 90972.6 91478.5 96137.0 96264.0 96332.5 77275.0 79626.6 80805.2 82254.0 83058.0 72393.6 72494.2 72670.0 74241.5 74256.0 7584.5 7699.0 7889.7 7935.2 7965.2 50739.5 51462.0 52028.3 52112.0 52784.0 68508.7 69237.3 69563.0 70187.3 70855.3 9723.2 9884.9 10123.7 10213.6 10511.1 10668.8 10804.9 10809.0 10947.8 11227.3 10365.1 10573.2 10630.3 10659.9 10675.4 10218.7 10278.9 10421.0 10599.0 10706.3 10022.1 10042.1 10104.4 10255.7 10854.0 8653.2 8695.4 8748.4 8931.2 9025.2 8822.5 8839.2 8878.2 8897.7 9007.3 07:07:55 INFO - PROCESS | 2562 | -------- Summary: end -------- 07:07:55 INFO - PROCESS | 2562 | 07:07:56 INFO - TEST-INFO | 2562: exit 0 07:07:56 INFO - TEST-OK | dromaeo_css | took 1115020ms 07:07:56 INFO - TEST-START | kraken 07:07:56 INFO - operating with platform_type : mac_ 07:07:56 INFO - Initialising browser for kraken test... 07:07:56 INFO - TEST-INFO | started process 2564 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmpJJjNOX/profile http://localhost:56161/getInfo.html) 07:07:58 INFO - PROCESS | 2564 | __metrics Screen width/height:1600/1200 07:07:58 INFO - PROCESS | 2564 | colorDepth:24 07:07:58 INFO - PROCESS | 2564 | Browser inner width/height: 1024/689 07:07:58 INFO - PROCESS | 2564 | __metrics 07:07:58 INFO - PROCESS | 2564 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:07:58 ERROR - PROCESS | 2564 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:07:59 INFO - TEST-INFO | 2564: exit 0 07:07:59 INFO - Browser initialized. 07:07:59 INFO - Running cycle 1/1 for kraken test... 07:07:59 INFO - TEST-INFO | started process 2566 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmpJJjNOX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:08:11 INFO - PROCESS | 2566 | RSS: Main: 211918848 07:08:11 INFO - PROCESS | 2566 | 07:08:13 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:56161/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56161/tests/kraken/driver.html?testName=audio-beat-detection) 07:08:14 INFO - PROCESS | 2566 | RSS: Main: 272363520 07:08:14 INFO - PROCESS | 2566 | 07:08:17 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:56161/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56161/tests/kraken/driver.html?testName=audio-dft) 07:08:18 INFO - PROCESS | 2566 | RSS: Main: 286224384 07:08:18 INFO - PROCESS | 2566 | 07:08:21 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:56161/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56161/tests/kraken/driver.html?testName=audio-fft) 07:08:22 INFO - PROCESS | 2566 | RSS: Main: 373436416 07:08:22 INFO - PROCESS | 2566 | 07:08:24 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:56161/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56161/tests/kraken/driver.html?testName=audio-oscillator) 07:08:24 INFO - PROCESS | 2566 | RSS: Main: 280162304 07:08:24 INFO - PROCESS | 2566 | 07:08:26 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:56161/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56161/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:08:27 INFO - PROCESS | 2566 | RSS: Main: 280608768 07:08:27 INFO - PROCESS | 2566 | 07:08:30 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:56161/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56161/tests/kraken/driver.html?testName=imaging-darkroom) 07:08:30 INFO - PROCESS | 2566 | RSS: Main: 317759488 07:08:30 INFO - PROCESS | 2566 | 07:08:33 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:56161/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56161/tests/kraken/driver.html?testName=imaging-desaturate) 07:08:34 INFO - PROCESS | 2566 | RSS: Main: 321327104 07:08:34 INFO - PROCESS | 2566 | 07:08:37 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:56161/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56161/tests/kraken/driver.html?testName=json-parse-financial) 07:08:37 INFO - PROCESS | 2566 | RSS: Main: 318124032 07:08:37 INFO - PROCESS | 2566 | 07:08:39 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:56161/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56161/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:08:39 INFO - PROCESS | 2566 | RSS: Main: 310001664 07:08:39 INFO - PROCESS | 2566 | 07:08:41 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:56161/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56161/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:08:42 INFO - PROCESS | 2566 | RSS: Main: 316162048 07:08:42 INFO - PROCESS | 2566 | 07:08:44 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:56161/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56161/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:08:45 INFO - PROCESS | 2566 | RSS: Main: 319651840 07:08:45 INFO - PROCESS | 2566 | 07:08:47 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:56161/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56161/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:08:48 INFO - PROCESS | 2566 | RSS: Main: 324698112 07:08:48 INFO - PROCESS | 2566 | 07:08:51 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:56161/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56161/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:08:52 INFO - PROCESS | 2566 | RSS: Main: 322531328 07:08:52 INFO - PROCESS | 2566 | 07:08:53 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:56161/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56161/tests/kraken/driver.html?testName=ai-astar) 07:08:54 INFO - PROCESS | 2566 | RSS: Main: 386818048 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | __start_tp_report 07:08:54 INFO - PROCESS | 2566 | _x_x_mozilla_page_load 07:08:54 INFO - PROCESS | 2566 | _x_x_mozilla_page_load_details 07:08:54 INFO - PROCESS | 2566 | |i|pagename|runs| 07:08:54 INFO - PROCESS | 2566 | |0;ai-astar;114;113;118;113;111;111;113;115;112;133 07:08:54 INFO - PROCESS | 2566 | |1;audio-beat-detection;157;122;160;172;146;172;171;120;172;121 07:08:54 INFO - PROCESS | 2566 | |2;audio-dft;185;183;187;194;257;184;185;187;183;183 07:08:54 INFO - PROCESS | 2566 | |3;audio-fft;78;75;76;154;55;56;60;66;133;57 07:08:54 INFO - PROCESS | 2566 | |4;audio-oscillator;93;92;137;77;79;97;125;79;76;137 07:08:54 INFO - PROCESS | 2566 | |5;imaging-gaussian-blur;89;85;88;86;87;84;87;84;87;83 07:08:54 INFO - PROCESS | 2566 | |6;imaging-darkroom;93;94;94;94;93;94;94;94;92;94 07:08:54 INFO - PROCESS | 2566 | |7;imaging-desaturate;86;84;87;85;85;84;86;86;85;83 07:08:54 INFO - PROCESS | 2566 | |8;json-parse-financial;60;63;64;63;64;65;65;80;61;57 07:08:54 INFO - PROCESS | 2566 | |9;json-stringify-tinderbox;64;65;77;77;76;73;70;72;70;74 07:08:54 INFO - PROCESS | 2566 | |10;stanford-crypto-aes;81;81;81;82;81;79;82;80;82;85 07:08:54 INFO - PROCESS | 2566 | |11;stanford-crypto-ccm;131;131;130;127;127;127;128;125;173;161 07:08:54 INFO - PROCESS | 2566 | |12;stanford-crypto-pbkdf2;167;169;164;174;168;150;194;210;166;165 07:08:54 INFO - PROCESS | 2566 | |13;stanford-crypto-sha256-iterative;68;69;70;67;67;66;66;66;67;68 07:08:54 INFO - PROCESS | 2566 | __end_tp_report 07:08:54 INFO - PROCESS | 2566 | __start_cc_report 07:08:54 INFO - PROCESS | 2566 | _x_x_mozilla_cycle_collect,2049 07:08:54 INFO - PROCESS | 2566 | __end_cc_report 07:08:54 INFO - PROCESS | 2566 | __startTimestamp1465222134186__endTimestamp 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | ------- Summary: start ------- 07:08:54 INFO - PROCESS | 2566 | Number of tests: 14 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | [#0] ai-astar Cycles:10 Average:115.30 Median:113.50 stddev:6.55 (5.8%) stddev-sans-first:6.93 07:08:54 INFO - PROCESS | 2566 | Values: 114.0 113.0 118.0 113.0 111.0 111.0 113.0 115.0 112.0 133.0 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | [#1] audio-beat-detection Cycles:10 Average:151.30 Median:165.50 stddev:22.52 (13.6%) stddev-sans-first:23.80 07:08:54 INFO - PROCESS | 2566 | Values: 157.0 122.0 160.0 172.0 146.0 172.0 171.0 120.0 172.0 121.0 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | [#2] audio-dft Cycles:10 Average:192.80 Median:186.00 stddev:22.80 (12.3%) stddev-sans-first:24.01 07:08:54 INFO - PROCESS | 2566 | Values: 185.0 183.0 187.0 194.0 257.0 184.0 185.0 187.0 183.0 183.0 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | [#3] audio-fft Cycles:10 Average:81.00 Median:63.00 stddev:34.39 (54.6%) stddev-sans-first:36.46 07:08:54 INFO - PROCESS | 2566 | Values: 78.0 75.0 76.0 154.0 55.0 56.0 60.0 66.0 133.0 57.0 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | [#4] audio-oscillator Cycles:10 Average:99.20 Median:79.00 stddev:24.64 (31.2%) stddev-sans-first:26.04 07:08:54 INFO - PROCESS | 2566 | Values: 93.0 92.0 137.0 77.0 79.0 97.0 125.0 79.0 76.0 137.0 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.94 (2.2%) stddev-sans-first:1.73 07:08:54 INFO - PROCESS | 2566 | Values: 89.0 85.0 88.0 86.0 87.0 84.0 87.0 84.0 87.0 83.0 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 07:08:54 INFO - PROCESS | 2566 | Values: 93.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 92.0 94.0 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.20 (1.4%) stddev-sans-first:1.22 07:08:54 INFO - PROCESS | 2566 | Values: 86.0 84.0 87.0 85.0 85.0 84.0 86.0 86.0 85.0 83.0 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:64.00 stddev:6.09 (9.5%) stddev-sans-first:6.26 07:08:54 INFO - PROCESS | 2566 | Values: 60.0 63.0 64.0 63.0 64.0 65.0 65.0 80.0 61.0 57.0 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:73.50 stddev:4.61 (6.3%) stddev-sans-first:3.94 07:08:54 INFO - PROCESS | 2566 | Values: 64.0 65.0 77.0 77.0 76.0 73.0 70.0 72.0 70.0 74.0 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.50 stddev:1.58 (1.9%) stddev-sans-first:1.67 07:08:54 INFO - PROCESS | 2566 | Values: 81.0 81.0 81.0 82.0 81.0 79.0 82.0 80.0 82.0 85.0 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | [#11] stanford-crypto-ccm Cycles:10 Average:136.00 Median:130.50 stddev:16.69 (12.8%) stddev-sans-first:17.61 07:08:54 INFO - PROCESS | 2566 | Values: 131.0 131.0 130.0 127.0 127.0 127.0 128.0 125.0 173.0 161.0 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.70 Median:168.50 stddev:17.03 (10.1%) stddev-sans-first:17.94 07:08:54 INFO - PROCESS | 2566 | Values: 167.0 169.0 164.0 174.0 168.0 150.0 194.0 210.0 166.0 165.0 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - PROCESS | 2566 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:1.35 (2.0%) stddev-sans-first:1.41 07:08:54 INFO - PROCESS | 2566 | Values: 68.0 69.0 70.0 67.0 67.0 66.0 66.0 66.0 67.0 68.0 07:08:54 INFO - PROCESS | 2566 | -------- Summary: end -------- 07:08:54 INFO - PROCESS | 2566 | 07:08:54 INFO - TEST-INFO | 2566: exit 0 07:08:54 INFO - TEST-OK | kraken | took 58826ms 07:08:54 INFO - SUITE-END | took 1173s 07:08:55 INFO - Completed test suite (00:19:34) 07:08:55 INFO - javascript benchmark 07:08:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3282.2778988622495, 3302.1744563859033, 3314.2377253231753, 3329.6004479462467, 3387.8983630491084, 5631.1271752878265, 5632.380438151805, 5641.633293835903, 5643.5203007744285, 5651.350094739107, 5623.297087864017, 5650.9152362714485, 5680.545556355492, 5680.715964201794, 5689.431056894308, 4315.611594956454, 5133.358330208724, 5199.860620239929, 5207.739613019353, 5274.393444753845, 4349.673774466912, 5264.92102618461, 5279.128943724281, 5282.07563676357, 5292.179712144604, 5209.739513024352, 5248.8687782805355, 5266.81566145185, 5270.683758974455, 5281.36623605166, 5266.657667251617, 5283.9698001952065, 5289.603279754034, 5289.788408463657, 5310.867228319285, 5080.618953578496, 5117.590592752571, 5121.974390128025, 5181.922271165936, 5190.766415511311, 4549.658775591844, 5569.498745112919, 5595.692236926968, 5604.579656525777, 5626.746796394172, 6181.84545386369, 6219.378062193789, 6221.4089661482085, 6235.064740288947, 6241.563090583661, 6103.176071230416, 6196.473299769525, 6285.748570057192, 6302.96848515759, 6335.208098987627, 5782.248307720011, 5802.274715660543, 5815.9709201453725, 5816.272965879265, 5836.708164591753, 6322.873542529123, 6334.208223972004, 6334.651594162273, 6336.714847831859, 6361.173047503795, 6038.9396106038375, 6105.277797093309, 6111.786087487006, 6172.135900973867, 6229.53278504114, 6271.874562508724, 6292.905606415816, 6298.338674439209, 6306.148669929593, 6313.179286692701, 6185.257769067741, 6225.3774622538285, 6246.593971391845, 6250.781222657185, 6259.812205633747, 6095.4818840398175, 6144.740328232791, 6167.198264225622, 6183.072539119078, 6255.655938923312, 4809.0, 4906.852794416173, 4912.557869791736, 4921.8523444296725, 4925.9901720148, 4041.3533834586915, 4264.829406823755, 4403.185410699031, 4409.074094440203, 4410.911781764346, 5654.802081927113, 5655.575831812631, 5686.061799802987, 5693.850780809685, 5706.0, 6123.5876691309595, 6191.4118158774845, 6308.810735677937, 6310.652914089769, 6318.684065796691, 5686.658800471985, 5733.770649173986, 5735.082386818089, 5743.4543718346695, 5745.1382292656435, 5317.6277660564165, 5442.91835622466, 5446.754896029688, 5462.016836969304, 5471.863203419866, 6205.627662340274, 6275.403836635513, 6359.936400635935, 6368.363163683594, 6398.1362516059735, 6277.874442511033, 6286.96265116269, 6290.119383286435, 6294.842628934313, 6325.841853953504, 6306.306306306395, 6349.777757778455, 6360.713767880364, 6365.90451143224, 6367.299597044229, 6069.757209711562, 6139.84650383744, 6142.314611233224, 6212.316645169119, 6263.123162757127, 6224.190855188796, 6274.6548939808745, 6280.434760871543, 6292.433680968735, 6324.778632747832, 6157.4458298753325, 6163.691815409301, 6165.691715414301, 6190.288116866618, 6192.287886893065, 5932.555058370725, 6090.725917333642, 6090.756369745338, 6095.2685677718955, 6096.542759292982, 4628.282616194495, 5882.764689412375, 5890.9116363253725, 5962.850928726816, 5993.910091348546, 6115.969420152871, 6125.234345706786, 6133.49188346556, 6138.882085680433, 6158.476529495043, 5799.884002319845, 5895.145203945318, 5949.881002379841, 5951.434613711691, 5958.731857066529], "value": 5679.10501707487, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [518.5462720119892, 534.2147043845547, 581.3546962871212, 587.632729544035, 611.1901730207474, 31207.68792312079, 31883.68116318836, 38778.80610596947, 39476.407853882214, 39524.0, 42548.57451425485, 43160.56839431605, 43201.56798432015, 43249.56750432495, 43272.0, 8311.83376332473, 9422.952885235563, 9636.566354514063, 9648.228141748643, 9648.855267170962, 8527.44571602847, 11503.597374091909, 11713.180077394543, 11722.589709360174, 11727.120465965045, 11456.198066135374, 11537.365444900528, 11539.942300288487, 11542.07663386927, 11548.249363791369, 8522.275606573448, 11226.494807733672, 11296.265742726737, 11584.47869845859, 11782.705432364173, 11126.443677816076, 11223.214374993753, 11240.550377984871, 11246.943765281121, 11267.0, 39220.60779392227, 39635.81092567199, 39666.61166859146, 39693.80153099245, 39838.80080599579, 3242.91892702682, 3350.1792060945227, 3411.306042885004, 3706.3513885070274, 3912.43269725891, 25881.611775823378, 26450.735492645214, 27447.90208391662, 27902.441951160865, 28141.57787633187, 2715.389037466574, 2766.9308267293277, 2787.5261205595098, 2825.519661657523, 2839.1482555233347, 351392.0, 353712.0, 353895.0, 354299.4570054318, 355648.2217588921, 60356.0, 63955.0, 64825.35174648288, 64851.0, 64910.35089649043, 68066.65966670234, 68414.65792671005, 71323.0, 72649.0, 72790.27209727834, 15355.462558810388, 17589.7361539577, 17845.84002039863, 17851.73222401665, 17859.017754023655, 12407.503699852085, 12687.873121268669, 12778.680532986564, 13496.797548036788, 13806.309684515734, 19894.90052549728, 20139.19443222284, 20153.09311081005, 20168.596628067357, 20236.898815506123, 6920.342567456084, 8062.556559389172, 8250.298724608356, 8264.710735124241, 8306.127856575093, 961.6297725375641, 1029.6910926721953, 1067.4395942130443, 1110.0952723530459, 1158.4207896051973, 26942.32644183911, 30070.54894176589, 30518.69481305203, 30781.029022169616, 31241.0, 1081.0594782539083, 1093.136422226448, 1151.7984352738238, 1159.4492616007499, 1174.6123779152858, 15250.161241131844, 15533.611659708598, 15824.683506330039, 15880.52358429249, 16019.279132439067, 620977.0, 622821.0, 626504.0, 628750.0, 629399.0, 63286.68356658187, 63812.042819357746, 63908.0, 64041.67979160074, 64105.03842442367, 1395.2047333020082, 1420.978685319721, 1538.8691961183424, 1555.1368990210315, 1561.6252099496267, 23654.52690946137, 28680.713192867803, 28991.710082898902, 29507.967221147155, 29671.55492667655, 51192.0, 51708.0, 51749.223761642854, 51904.961900761016, 51912.70218244574, 429824.0, 432862.0, 432929.0, 434522.0, 434984.8250758726, 23341.0, 27181.72818271792, 27447.313817154733, 27712.58431123575, 27788.444231115667, 40975.0, 41732.58267417287, 42395.1520969585, 44013.0, 44546.77726611346, 34317.82841085779, 34540.13649658779, 35454.46818297779, 36344.636553634125, 36423.635763643084, 34822.82588587041, 35281.47077793784, 35329.82335088308, 35852.462213067345, 36022.63977360193, 37122.0, 37480.81259593685, 38042.0, 38056.23887522179, 38282.80858595689, 31280.21799455032, 32725.34549309048, 32788.180295492806, 32804.01587952365, 32859.0, 24086.638700419157, 24388.390290242885, 24553.0, 24853.25440236796, 24883.253502394957], "value": 20025.16917280274, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.47409811509175, 396.7797872180938, 401.60642570281107, 402.55115546166024, 409.844259181511, 15165.0, 18450.0, 20254.6961795573, 20977.0, 20982.16071357146, 21007.567999600386, 21262.787372126313, 22214.777852221472, 22333.888330558326, 22345.10619575215, 5950.523958083354, 5972.970135149319, 6080.787172448965, 6190.876182476371, 6210.068489726532, 4259.936100958473, 6158.107074474198, 6159.291681456622, 6204.945159322903, 6519.739210431577, 5970.79102231422, 6629.734810607594, 6796.524243302971, 6806.727730890759, 6811.284815094418, 4823.589994850442, 6103.115048318014, 6114.724837382329, 6768.984652302145, 6778.254392016875, 5872.5889187756875, 5896.056630939029, 5899.646021238739, 5914.2311499504785, 5921.911171332434, 13439.0, 13745.24401157936, 14069.366878490493, 14075.788863167061, 14099.506517271846, 2369.218158007853, 2399.040383846458, 2504.8246622736415, 2858.6283783108065, 2863.985680071586, 10095.747606309827, 10463.84304235437, 13766.174029558259, 14848.554543363716, 14871.479498217619, 1729.9135043247786, 1750.2561411400231, 1763.2946821271466, 1786.1337251433044, 1815.636872625467, 110703.0, 115758.0, 118355.0, 118466.22300665497, 119437.0, 11685.649430517098, 11782.882171178178, 11903.047756179658, 11988.64034078979, 12036.63890083299, 13859.168449893039, 14375.496857609933, 14594.978351515505, 15276.388944442098, 15340.079595224322, 8914.465132092095, 8985.0, 9002.279817614575, 9008.009118996904, 9112.362134650644, 9001.459912405277, 9017.413868098683, 9038.774030649287, 9072.319576031825, 9139.177474027369, 4681.836135735233, 4718.127146477913, 4720.929186062212, 4721.976390118028, 4765.189917713997, 1720.4838548435669, 1745.7381392790928, 1951.5999220159833, 2036.287299445212, 2050.1389416445127, 902.2105657549644, 906.7506435730227, 910.2081189365294, 976.3799987008159, 1011.6155860772859, 19372.4188274352, 20117.798822011886, 20178.991050447417, 21670.783292166878, 21716.782832171793, 951.7810903492098, 965.3483898368573, 990.6087095597198, 999.1107913956585, 1041.1358572384902, 11225.326480411202, 11313.648012069028, 11327.773444531063, 11328.150388720878, 11381.829272560919, 592137.0, 616903.0, 622229.0, 636433.0, 640826.0, 93497.0, 124379.3781031089, 124475.3776231113, 129378.0, 132478.01282980762, 997.4190907411834, 1003.287665757306, 1015.2030655935204, 1016.4511163971521, 1037.5849660135855, 18286.354228119002, 18859.774114682503, 19106.617867642846, 19144.521386965436, 19438.319658811874, 37491.250174996894, 49270.0, 52368.0, 52777.208341875674, 55826.72086639541, 65767.0, 68813.65593172002, 72210.27789722246, 72614.54770904446, 77938.83091753742, 6879.6216208107035, 7901.446898717035, 8254.876176857471, 8302.294304984143, 8329.54187519668, 12051.156419050581, 12129.939350303192, 12344.321062341658, 12493.500259989498, 12599.496020159457, 10731.946340268249, 10996.395198264172, 11024.614138505434, 11043.337399756041, 11076.501557430096, 10420.218483613608, 10468.371897686164, 10493.213009024506, 10805.945970270099, 10875.891241087487, 10714.678559643224, 10777.515011824644, 10983.450827458435, 11170.832437513282, 11260.0, 7970.0, 8188.222118898695, 8233.79415514617, 8234.464759790595, 8276.0, 9446.574904129193, 9689.41863488193, 10447.582096716047, 10682.786344273227, 10993.835092473779], "value": 9565.733883697598, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1402.263811498963, 1446.3925151436408, 1605.6226786705126, 1806.4670922077985, 1997.570522337698, 7844.980152580164, 8519.148085191486, 8726.694565690203, 8738.169873861973, 8738.563071846414, 8537.871931921027, 8547.957260213705, 8676.132386761321, 8698.826023479527, 8709.0, 2162.6323525000707, 2442.0842184180888, 2474.0845887021737, 2475.344033831027, 2477.071098338123, 2221.9000144993515, 2228.565429741201, 2230.4981379189635, 2434.573949558829, 2488.208489887278, 2423.6970378702663, 2427.2960841356075, 2465.852048877064, 2467.7655622715815, 2469.394998225437, 2413.650020746991, 2439.926802195937, 2448.6939132608422, 2450.9509809803703, 2451.019592163131, 1943.5821298420908, 2180.1824315881545, 2189.43074800553, 2417.9274621761374, 2476.219990702941, 8369.748907532723, 8466.280366168883, 8466.576671166416, 8476.449030812977, 8483.448575842613, 5186.662866913639, 5286.392064912546, 5291.629585928987, 5299.894002119936, 5309.4425085365865, 3215.4051500472256, 3254.739620830328, 3258.201740573551, 3368.59576850777, 3432.622411534729, 1065.2170654568886, 1083.025277250473, 1083.9078678312353, 1085.1535802074395, 1087.5812812067331, 11764.058875289957, 11966.940165299206, 11984.161108722394, 11996.820047699204, 12004.219725717887, 3871.728978971445, 4668.369770080995, 4795.736234507136, 4843.418789745253, 4850.296706977469, 8416.326693864476, 10093.343932644355, 10093.394396336244, 10097.0, 10119.696409107739, 5332.866678332995, 5336.653117547425, 5380.085385484477, 5387.138057910715, 5392.487713667196, 4224.619784219435, 4857.489963553847, 4867.099586576494, 4868.902621947612, 4871.098846713369, 3769.4911186989953, 3830.4637350770913, 3898.142408670088, 3899.161680238762, 4098.159877225162, 1326.6086504481202, 1337.6120924931856, 1350.4530665080658, 1354.512375544803, 1362.455017992791, 914.1909410172045, 919.6275508419101, 944.1031020530386, 945.2721404518483, 960.9087136722001, 5143.948560514422, 5768.394318596571, 5848.327442344185, 5852.882942341129, 5953.077273022688, 447.0634021724482, 450.6890245730435, 458.136412861758, 465.4763391184917, 474.42120612852267, 3867.3038853006165, 3921.7254792164276, 3970.82131304092, 4041.1715598302276, 4049.230646177276, 10117.241206909512, 10483.056524912641, 10491.16070714341, 10609.84085238722, 10617.734556636145, 9787.119159275557, 10229.59081636741, 10255.487225638688, 10396.48017599132, 10399.94800025995, 7786.610669466618, 7874.133845277015, 8134.389920755967, 8139.715109971123, 8226.465279756796, 4748.952510474989, 5703.486686198262, 5807.0, 5852.912206316993, 5878.617889837147, 9007.864882026644, 9217.0, 9369.765755856159, 9469.431834089977, 9470.95264523673, 9620.422774633544, 9688.564014619218, 9708.417494950325, 9760.804783904425, 9766.706998790047, 7386.667599957907, 7512.924870751222, 7520.247975202436, 7595.886061708969, 7618.085829700472, 7196.136463624399, 7388.556686598821, 7396.815079623052, 7984.840303193788, 8029.156938521372, 6622.867542649024, 6848.280930502425, 7164.641767911479, 7347.5224110432655, 7529.548227106391, 6525.0, 6760.459163266829, 6834.45324374039, 7068.71725130989, 7150.0, 6895.103636527219, 6910.343517365842, 6967.442604591519, 6968.477364197807, 6973.442124629916, 4640.164770341371, 4782.402199725035, 4915.615926275682, 4978.5270399312, 4995.075910956485, 4836.830710925101, 4884.82903098404, 4945.703257804399, 4981.452040275639, 4989.151844186425], "value": 4441.635515945506, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.46951000359405, 325.277883099519, 331.40016570008277, 334.79912052768327, 336.4499044062956, 6635.86728265435, 6654.96672516638, 6660.50046246531, 6683.365080317368, 6692.464602831774, 6534.15056042715, 6607.966960165192, 6670.966645166767, 6680.565763225387, 6691.6319602421845, 4724.669273150881, 4823.493533179018, 4831.275308703687, 4920.75396230187, 4929.2606109083745, 4885.902281954359, 4956.950430495685, 4984.87537811554, 4987.226979818116, 4990.3762029746285, 4881.023795240948, 4897.608191344684, 4914.778834952435, 4917.901641967176, 4939.753012349386, 4677.812887484513, 4797.4003249593625, 4800.0, 4895.922896962663, 4907.460179380265, 3430.6054803697643, 4202.159568086365, 4207.978960105211, 4225.112726327475, 4235.618794308527, 6352.555321127523, 6373.553851230416, 6376.202974628171, 6389.073584330293, 6407.199100112486, 5825.592208545403, 5828.5337173026055, 5829.5919285650025, 5836.941630583682, 5851.180834683148, 6794.3545363190415, 7064.399526040293, 7065.82335441618, 7067.22260551339, 7088.326608972139, 3937.3109705801658, 4738.502457241974, 4738.857834264943, 4739.14695354836, 4754.524547545252, 7348.265173482662, 7526.397888168988, 7527.172011078777, 7531.736389226406, 7548.849023019508, 4805.447373552016, 4837.806487740521, 4839.782209800567, 4840.2255639097575, 4844.927326090112, 4893.608511319086, 4905.509449055137, 4907.55831975124, 4910.459849416561, 4913.606911447076, 5602.607817452821, 5812.622179558315, 5831.591788574844, 5834.416558344132, 5837.7956771513645, 7124.216336203013, 7130.964345178241, 7172.677229524684, 7178.066851309296, 7190.64046797658, 1667.2164082881027, 1706.6671998960085, 1707.2061491406555, 1711.3154738104608, 1714.7170716831583, 913.3013244867682, 1035.4770840725384, 1050.9316894401838, 1083.8320060390652, 1110.8944650258213, 6534.248561415498, 6727.9327206728285, 6732.831679207961, 6750.83122921921, 6754.223264324665, 7454.776356709307, 7471.514351567131, 7471.887921681179, 7475.401967842559, 7478.626068696543, 5589.35722391922, 5669.951059054089, 6388.872222555523, 6708.0, 6713.295104014008, 3187.6015498062743, 3779.7543159694533, 3925.1953349563273, 3937.192875460581, 3945.980270098631, 7759.030121234846, 7781.027371578552, 7791.091182660284, 7799.142094369615, 7809.414293927978, 7578.317951384402, 7655.770326890202, 7671.619108560401, 7676.923230767732, 7677.923220767832, 6619.569727967667, 7721.227877212234, 7777.144514103444, 7806.453548251568, 7810.0, 7066.434685225273, 7383.630818459163, 7418.221086785809, 7419.629018549159, 7429.777106686808, 7251.56490610565, 7273.272672732896, 7285.562866228043, 7310.305520975498, 7310.451716121206, 7762.961185194038, 7767.145613982344, 7779.961100194463, 7785.0, 7806.258405451473, 4825.806967721252, 4842.539958703917, 4845.636577256791, 4852.126617208936, 4858.416989961227, 7083.397911177608, 7365.116186057708, 7367.889481657672, 7371.3734332582335, 7376.225496323023, 5809.331926828299, 7067.434605231672, 7143.535670181422, 7144.035555199982, 7167.964160179165, 7116.679749411185, 7125.928740712527, 7146.642667866482, 7164.785056448315, 7164.89252661221, 6982.1272340957385, 6988.40598549129, 6991.825204369931, 7000.194977577644, 7013.228544860163, 6892.794305822089, 7136.464765142531, 7150.392216661642, 7153.928460715326, 7165.283471652793, 6767.323267673391, 6963.1644202696, 6967.825804354931, 6979.825504362431, 7007.299270072952], "value": 5220.8239470073695, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.5637904499208, 705.4391758551951, 734.3390948146671, 765.8200322924115, 795.5544894858878, 13226.0, 14271.857281427183, 15218.847811521882, 15234.009789363683, 15254.999999999985, 13791.241481718502, 13866.653333666663, 13877.930610346959, 13882.93058534706, 13885.930570347135, 734.0787311923555, 751.9661615227301, 879.3756432932626, 912.1289168843738, 920.8756817829574, 902.4224496322358, 916.6974898283584, 918.2745630951574, 925.0703043441332, 939.8355287824637, 971.4656938683731, 973.0318333258418, 975.3124047546463, 977.0034564743956, 989.9506523085516, 777.3392616276149, 972.5817898303727, 973.5521660036392, 979.8187335342984, 988.2785566536397, 725.8076609698381, 726.3281464645182, 728.6538894025349, 731.7950973727364, 744.2222877093396, 14846.851531484655, 14910.478133265393, 14923.850761492355, 14928.552143435714, 14931.0, 12292.877071229352, 12719.0, 12755.703973284804, 12932.224066556037, 12998.773700594269, 9879.983818042901, 9927.062424230413, 9947.403155810602, 10348.586056557804, 10399.376037437778, 4038.3942408638645, 4055.655269302113, 4058.7767672777986, 4131.5455299916985, 4267.146570685875, 66090.66954665244, 66640.33399165011, 66677.0, 67417.3258267416, 67428.98856517156, 8968.148025937526, 9205.953970230106, 9294.90705092954, 9440.197583205367, 9980.800383992424, 19202.61594768097, 19334.033298335027, 21138.47153821136, 21167.835769032543, 21176.89411552963, 7430.256974302634, 7463.365613922769, 7511.737089201852, 7513.32380085784, 7689.769306920802, 3888.7083468739957, 3971.4638523799495, 4157.314043182841, 4157.750534967911, 4443.933340999887, 3569.214772749991, 3624.1664417184197, 3624.873129440457, 3627.2020155565733, 3629.219717760694, 636.4280722276532, 647.922249330074, 656.7799787071289, 656.9146011018537, 663.5454713521253, 589.4105894105894, 594.5897330841792, 607.4108115128319, 608.3733754233147, 623.7037407231619, 7863.803404914808, 8284.71003514874, 8303.211194936472, 8340.582970851554, 8500.829983400297, 588.3733823477983, 591.6745789815584, 600.1477901380051, 618.77724019353, 620.1380081686459, 3698.2788356270808, 3821.4458903458844, 3823.2735780201674, 3902.70729695274, 3928.6071392860486, 90972.6354104689, 91478.54260728786, 96137.0, 96264.0, 96332.51833740786, 77275.0, 79626.60186699145, 80805.19194808094, 82254.0, 83058.0, 72393.6380318095, 72494.18764530824, 72670.0, 74241.5151696963, 74256.0, 7584.469087163846, 7698.961505192438, 7889.684412623431, 7935.2064793520185, 7965.203479651988, 50739.49260507348, 51462.0, 52028.276889542874, 52112.0, 52784.0, 68508.6574567124, 69237.2690682737, 69563.0, 70187.29812701808, 70855.29144708486, 9723.222142228464, 9884.901150988399, 10123.7469063274, 10213.642522511676, 10511.054005139575, 10668.786624267315, 10804.891951080703, 10809.027187553158, 10947.83578246343, 11227.326360418401, 10365.118964887768, 10573.154147668321, 10630.25588208849, 10659.94670026645, 10675.359478431319, 10218.744531386477, 10278.897211027794, 10421.0, 10599.046085852311, 10706.304090234422, 10022.14811740981, 10042.096211340722, 10104.44425556572, 10255.7436064099, 10854.0, 8653.17794809492, 8695.435816795727, 8748.387612867038, 8931.19619234272, 9025.232855207381, 8822.470651760916, 8839.16027977341, 8878.245349145394, 8897.688580899638, 9007.324450666358], "value": 6818.075706182602, "unit": "score"}], "name": "dromaeo_css", "value": 7457.49254858405, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 118.0, 113.0, 111.0, 111.0, 113.0, 115.0, 112.0, 133.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 122.0, 160.0, 172.0, 146.0, 172.0, 171.0, 120.0, 172.0, 121.0], "value": 151.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 187.0, 194.0, 257.0, 184.0, 185.0, 187.0, 183.0, 183.0], "value": 192.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 76.0, 154.0, 55.0, 56.0, 60.0, 66.0, 133.0, 57.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 137.0, 77.0, 79.0, 97.0, 125.0, 79.0, 76.0, 137.0], "value": 99.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 86.0, 87.0, 84.0, 87.0, 84.0, 87.0, 83.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 92.0, 94.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 85.0, 85.0, 84.0, 86.0, 86.0, 85.0, 83.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 64.0, 63.0, 64.0, 65.0, 65.0, 80.0, 61.0, 57.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 77.0, 77.0, 76.0, 73.0, 70.0, 72.0, 70.0, 74.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 82.0, 81.0, 79.0, 82.0, 80.0, 82.0, 85.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 131.0, 130.0, 127.0, 127.0, 127.0, 128.0, 125.0, 173.0, 161.0], "value": 136.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 164.0, 174.0, 168.0, 150.0, 194.0, 210.0, 166.0, 165.0], "value": 172.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 70.0, 67.0, 67.0, 66.0, 66.0, 66.0, 67.0, 68.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1497.8000000000004, "alertThreshold": 2.0}]} 07:08:55 INFO - Return code: 0 07:08:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:08:55 ERROR - # TBPL SUCCESS # 07:08:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:08:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:08:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:08:55 INFO - 2016-06-06 07:08:55.521 screenresolution[2570:30399] starting screenresolution argv=screenresolution get 07:08:55 INFO - 2016-06-06 07:08:55.523 screenresolution[2570:30399] Display 0: 1600x1200x32@60 07:08:55 INFO - 2016-06-06 07:08:55.529 screenresolution[2571:30402] starting screenresolution argv=screenresolution list 07:08:55 INFO - Available Modes on Display 0 07:08:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:08:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:08:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:08:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:08:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:08:55 INFO - Intel Iris: 07:08:55 INFO - Chipset Model: Intel Iris 07:08:55 INFO - Type: GPU 07:08:55 INFO - Bus: Built-In 07:08:55 INFO - VRAM (Dynamic, Max): 1536 MB 07:08:55 INFO - Vendor: Intel (0x8086) 07:08:55 INFO - Device ID: 0x0a2e 07:08:55 INFO - Revision ID: 0x0009 07:08:55 INFO - Displays: 07:08:55 INFO - Display: 07:08:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:08:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:08:55 INFO - Display Serial Number: 1600x1200 60 07:08:55 INFO - Main Display: Yes 07:08:55 INFO - Mirror: Off 07:08:55 INFO - Online: Yes 07:08:55 INFO - Rotation: Supported 07:08:55 INFO - Return code: 0 07:08:55 INFO - Running post-action listener: _resource_record_post_action 07:08:55 INFO - Running post-run listener: _resource_record_post_run 07:08:56 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 331776; Write bytes: 651006976; Read time: 2; Write time: 1857 07:08:56 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 354552320; Write bytes: 357391872; Read time: 27707; Write time: 776 07:08:56 INFO - run-tests - Wall time: 1176s; CPU: 52.0%; Read bytes: 331776; Write bytes: 292681216; Read time: 2; Write time: 1076 07:08:56 INFO - Running post-run listener: _upload_blobber_files 07:08:56 INFO - Blob upload gear active. 07:08:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:08:56 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 07:08:56 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'] 07:08:56 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 07:08:57 INFO - (blobuploader) - INFO - Open directory for files ... 07:08:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:08:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:08:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:08:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:08:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:08:58 INFO - (blobuploader) - INFO - Done attempting. 07:08:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:08:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:08:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:08:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:08:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:08:58 INFO - (blobuploader) - INFO - Done attempting. 07:08:58 INFO - (blobuploader) - INFO - Iteration through files over. 07:08:58 INFO - Return code: 0 07:08:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:08:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:08:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebe6c048dfd6a256f9c5c77b1d5afeb988b36c55024c8b740672f63e775be43d9571bb2ea06ef79a5aeeee143d0e8c48d12d5dcbdf569d660e793e41d1355f07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be8d8a0b264ce20df62b9a23c676635f40970c85ee918582e3bc0ee2d17c834265f2e8edef4240809161380f1a0461a347c312c315dfa31b643eb470b246b0d9"} 07:08:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:08:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:08:58 INFO - Contents: 07:08:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebe6c048dfd6a256f9c5c77b1d5afeb988b36c55024c8b740672f63e775be43d9571bb2ea06ef79a5aeeee143d0e8c48d12d5dcbdf569d660e793e41d1355f07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be8d8a0b264ce20df62b9a23c676635f40970c85ee918582e3bc0ee2d17c834265f2e8edef4240809161380f1a0461a347c312c315dfa31b643eb470b246b0d9"} 07:08:58 INFO - Running post-run listener: copy_logs_to_upload_dir 07:08:58 INFO - Copying logs to upload dir... 07:08:58 INFO - mkdir: /builds/slave/test/build/upload/logs 07:08:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.758135 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-06-06 07:08:59.016189) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 07:08:59.019867) ========= 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.NR8iTrYYtn/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.GKpv4XcW1Q/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebe6c048dfd6a256f9c5c77b1d5afeb988b36c55024c8b740672f63e775be43d9571bb2ea06ef79a5aeeee143d0e8c48d12d5dcbdf569d660e793e41d1355f07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be8d8a0b264ce20df62b9a23c676635f40970c85ee918582e3bc0ee2d17c834265f2e8edef4240809161380f1a0461a347c312c315dfa31b643eb470b246b0d9"} build_url:https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011570 build_url: 'https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebe6c048dfd6a256f9c5c77b1d5afeb988b36c55024c8b740672f63e775be43d9571bb2ea06ef79a5aeeee143d0e8c48d12d5dcbdf569d660e793e41d1355f07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be8d8a0b264ce20df62b9a23c676635f40970c85ee918582e3bc0ee2d17c834265f2e8edef4240809161380f1a0461a347c312c315dfa31b643eb470b246b0d9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 07:08:59.052571) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 07:08:59.052981) ========= 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.NR8iTrYYtn/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.GKpv4XcW1Q/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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.005331 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 07:08:59.102485) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 07:08:59.102880) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 07:08:59.103285) ========= ========= Total master_lag: 0.17 =========